Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

SSSD blocks for 80 seconds trying to connect to LDAP #1751

Closed
sssd-bot opened this issue May 2, 2020 · 0 comments
Closed

SSSD blocks for 80 seconds trying to connect to LDAP #1751

sssd-bot opened this issue May 2, 2020 · 0 comments
Assignees
@sumit-bose
Labels
Closed: Not a bug

Comments

@sssd-bot
Copy link

sssd-bot commented May 2, 2020

Cloned from Pagure issue: https://pagure.io/SSSD/sssd/issue/709

  • Created at 2010-12-11 20:22:44 by dpal
  • Closed as Invalid
  • Assigned to sbose

Was working semi-offline i.e. had internet connection but not VPN.

Every several minutes SSSD would block to try to establish LDAP connection and wait for 80 seconds:

(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_id_op_connect_step] (9): beginning to connect
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [fo_resolve_service_send] (4): Trying to resolve service 'LDAP'
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [get_server_status] (7): Status of server 'ldap.baz.foobar.com' is 'working'
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [get_port_status] (7): Port status of port 389 for server 'ldap.baz.foobar.com' is 'not working'
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [get_port_status] (4): Reseting the status of port 389 for server 'ldap.baz.foobar.com'
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [get_server_status] (7): Status of server 'ldap.baz.foobar.com' is 'working'
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [be_resolve_server_done] (4): Found address for server ldap.baz.foobar.com: [xxx.xxx.xxx.xxx]
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [setup_ldap_connection_callbacks] (9): LDAP connection callbacks are not supported.
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_get_rootdse_send] (9): Getting rootdse
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (6): calling ldap_search_ext with [(objectclass=*)][].
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [*]
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [altServer]
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [namingContexts]
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedControl]
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedExtension]
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedFeatures]
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedLDAPVersion]
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedSASLMechanisms]
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [defaultNamingContext]
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [lastUSN]
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [highestCommittedUSN]


<====== Here! It is 80 sec timeout (see more of those repeating below =======>



(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (3): ldap_search_ext failed: Can't contact LDAP server
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (3): Connection error: (null)
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [sdap_install_ldap_callbacks] (8): Trace: sh[0x8914460], connected[1], ops[(nil)], fde[0x8919578], ldap[0x81accc0]
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [fo_set_port_status] (4): Marking port 389 of server 'ldap.baz.foobar.com' as 'not working'
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [sdap_handle_release] (8): Trace: sh[0x8914460], connected[1], ops[(nil)], ldap[0x81accc0], destructor_lock[0], release_memory[0]
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [fo_resolve_service_send] (4): Trying to resolve service 'LDAP'
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [get_server_status] (7): Status of server 'ldap.baz.foobar.com' is 'working'
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [get_port_status] (7): Port status of port 389 for server 'ldap.baz.foobar.com' is 'not working'
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [fo_resolve_service_send] (1): No available servers for service 'LDAP'
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [sdap_id_op_connect_done] (1): Failed to connect, going offline (5 [Input/output error])
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [be_mark_offline] (8): Going offline!
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [be_run_offline_cb] (3): Going offline. Running callbacks.
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [sdap_id_op_connect_done] (9): notify offline to op #1
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [sdap_id_release_conn_data] (9): releasing unused connection
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:12:46 2010) [sssd[be[foobar.com]]] [remove_krb5_info_files] (5): Could not remove [/var/lib/sss/pubconf/kdcinfo.FOOBAR.COM], [2][No such file or directory]
(Sat Dec 11 14:12:46 2010) [sssd[be[foobar.com]]] [remove_krb5_info_files] (5): Could not remove [/var/lib/sss/pubconf/kpasswdinfo.FOOBAR.COM], [2][No such file or directory]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_id_op_connect_step] (9): beginning to connect
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [fo_resolve_service_send] (4): Trying to resolve service 'LDAP'
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [get_server_status] (7): Status of server 'ldap.baz.foobar.com' is 'working'
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [get_port_status] (7): Port status of port 389 for server 'ldap.baz.foobar.com' is 'not working'
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [get_port_status] (4): Reseting the status of port 389 for server 'ldap.baz.foobar.com'
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [get_server_status] (7): Status of server 'ldap.baz.foobar.com' is 'working'
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [be_resolve_server_done] (4): Found address for server ldap.baz.foobar.com: [xxx.xxx.xxx.xxx]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [setup_ldap_connection_callbacks] (9): LDAP connection callbacks are not supported.
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_get_rootdse_send] (9): Getting rootdse
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (6): calling ldap_search_ext with [(objectclass=*)][].
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [*]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [altServer]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [namingContexts]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedControl]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedExtension]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedFeatures]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedLDAPVersion]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedSASLMechanisms]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [defaultNamingContext]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [lastUSN]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [highestCommittedUSN]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (3): ldap_search_ext failed: Can't contact LDAP server
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (3): Connection error: (null)
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sdap_install_ldap_callbacks] (8): Trace: sh[0x89144b0], connected[1], ops[(nil)], fde[0x89167e0], ldap[0x81accc0]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [fo_set_port_status] (4): Marking port 389 of server 'ldap.baz.foobar.com' as 'not working'
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sdap_handle_release] (8): Trace: sh[0x89144b0], connected[1], ops[(nil)], ldap[0x81accc0], destructor_lock[0], release_memory[0]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [fo_resolve_service_send] (4): Trying to resolve service 'LDAP'
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [get_server_status] (7): Status of server 'ldap.baz.foobar.com' is 'working'
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [get_port_status] (7): Port status of port 389 for server 'ldap.baz.foobar.com' is 'not working'
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [fo_resolve_service_send] (1): No available servers for service 'LDAP'
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sdap_id_op_connect_done] (1): Failed to connect, going offline (5 [Input/output error])
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_mark_offline] (8): Going offline!
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_run_offline_cb] (3): Going offline. Running callbacks.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sdap_id_op_connect_done] (9): notify offline to op #1
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sdap_id_release_conn_data] (9): releasing unused connection
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][name=dpal]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [remove_krb5_info_files] (5): Could not remove [/var/lib/sss/pubconf/kdcinfo.FOOBAR.COM], [2][No such file or directory]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [remove_krb5_info_files] (5): Could not remove [/var/lib/sss/pubconf/kpasswdinfo.FOOBAR.COM], [2][No such file or directory]
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4099][1][name=dpal]
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4099][1][name=dpal]
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4099][1][name=dpal]
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:17:11 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:17:11 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:17:11 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:17:11 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:17:11 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_id_op_connect_step] (9): beginning to connect
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [fo_resolve_service_send] (4): Trying to resolve service 'LDAP'
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [get_server_status] (7): Status of server 'ldap.baz.foobar.com' is 'working'
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [get_port_status] (7): Port status of port 389 for server 'ldap.baz.foobar.com' is 'not working'
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [get_port_status] (4): Reseting the status of port 389 for server 'ldap.baz.foobar.com'
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [get_server_status] (7): Status of server 'ldap.baz.foobar.com' is 'working'
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [be_resolve_server_done] (4): Found address for server ldap.baz.foobar.com: [xxx.xxx.xxx.xxx]
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [setup_ldap_connection_callbacks] (9): LDAP connection callbacks are not supported.
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_get_rootdse_send] (9): Getting rootdse
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (6): calling ldap_search_ext with [(objectclass=*)][].
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [*]
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [altServer]
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [namingContexts]
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedControl]
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedExtension]
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedFeatures]
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedLDAPVersion]
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedSASLMechanisms]
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [defaultNamingContext]
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [lastUSN]
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [highestCommittedUSN]
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (3): ldap_search_ext failed: Can't contact LDAP server
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (3): Connection error: (null)
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sdap_install_ldap_callbacks] (8): Trace: sh[0x81acd40], connected[1], ops[(nil)], fde[0x89167e0], ldap[0x8919680]
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [fo_set_port_status] (4): Marking port 389 of server 'ldap.baz.foobar.com' as 'not working'
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sdap_handle_release] (8): Trace: sh[0x81acd40], connected[1], ops[(nil)], ldap[0x8919680], destructor_lock[0], release_memory[0]
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [fo_resolve_service_send] (4): Trying to resolve service 'LDAP'
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [get_server_status] (7): Status of server 'ldap.baz.foobar.com' is 'working'
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [get_port_status] (7): Port status of port 389 for server 'ldap.baz.foobar.com' is 'not working'
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [fo_resolve_service_send] (1): No available servers for service 'LDAP'
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sdap_id_op_connect_done] (1): Failed to connect, going offline (5 [Input/output error])
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [be_mark_offline] (8): Going offline!
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [be_run_offline_cb] (3): Going offline. Running callbacks.
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sdap_id_op_connect_done] (9): notify offline to op #1
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sdap_id_release_conn_data] (9): releasing unused connection
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:19:21 2010) [sssd[be[foobar.com]]] [remove_krb5_info_files] (5): Could not remove [/var/lib/sss/pubconf/kdcinfo.FOOBAR.COM], [2][No such file or directory]
(Sat Dec 11 14:19:21 2010) [sssd[be[foobar.com]]] [remove_krb5_info_files] (5): Could not remove [/var/lib/sss/pubconf/kpasswdinfo.FOOBAR.COM], [2][No such file or directory]

Comments

Comment from dpal at 2010-12-11 20:26:17

I am using latest 1.5 scratch build: 0.2010120820git583a018.el5

Comment from dpal at 2010-12-11 20:30:00

It seems to happen every 6 minutes. It freezes the machine.
It might be the same issue I reported yesterday.

Comment from dpal at 2010-12-11 21:24:44

Fields changed

description: Was working semi-offline i.e. had internet connection but not VPN.

Every several minutes SSSD would block to try to establish LDAP connection and wait for 80 seconds:

{{{
(Sat Dec 11 14:11:25 2010) [sssd[be[redhat.com]]] [sdap_id_op_connect_step] (9): beginning to connect
(Sat Dec 11 14:11:25 2010) [sssd[be[redhat.com]]] [fo_resolve_service_send] (4): Trying to resolve service 'LDAP'
(Sat Dec 11 14:11:25 2010) [sssd[be[redhat.com]]] [get_server_status] (7): Status of server 'ldap.bos.redhat.com' is 'working'
(Sat Dec 11 14:11:25 2010) [sssd[be[redhat.com]]] [get_port_status] (7): Port status of port 389 for server 'ldap.bos.redhat.com' is 'not working'
(Sat Dec 11 14:11:25 2010) [sssd[be[redhat.com]]] [get_port_status] (4): Reseting the status of port 389 for server 'ldap.bos.redhat.com'
(Sat Dec 11 14:11:25 2010) [sssd[be[redhat.com]]] [get_server_status] (7): Status of server 'ldap.bos.redhat.com' is 'working'
(Sat Dec 11 14:11:25 2010) [sssd[be[redhat.com]]] [be_resolve_server_done] (4): Found address for server ldap.bos.redhat.com: [10.16.255.3]
(Sat Dec 11 14:11:25 2010) [sssd[be[redhat.com]]] [setup_ldap_connection_callbacks] (9): LDAP connection callbacks are not supported.
(Sat Dec 11 14:11:25 2010) [sssd[be[redhat.com]]] [sdap_get_rootdse_send] (9): Getting rootdse
(Sat Dec 11 14:11:25 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (6): calling ldap_search_ext with [(objectclass=)][].
(Sat Dec 11 14:11:25 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: []
(Sat Dec 11 14:11:25 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [altServer]
(Sat Dec 11 14:11:25 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [namingContexts]
(Sat Dec 11 14:11:25 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedControl]
(Sat Dec 11 14:11:25 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedExtension]
(Sat Dec 11 14:11:25 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedFeatures]
(Sat Dec 11 14:11:25 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedLDAPVersion]
(Sat Dec 11 14:11:25 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedSASLMechanisms]
(Sat Dec 11 14:11:25 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [defaultNamingContext]
(Sat Dec 11 14:11:25 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [lastUSN]
(Sat Dec 11 14:11:25 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [highestCommittedUSN]

<====== Here! It is 80 sec timeout (see more of those repeating below =======>

(Sat Dec 11 14:12:45 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (3): ldap_search_ext failed: Can't contact LDAP server
(Sat Dec 11 14:12:45 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (3): Connection error: (null)
(Sat Dec 11 14:12:45 2010) [sssd[be[redhat.com]]] [sdap_install_ldap_callbacks] (8): Trace: sh[0x8914460], connected[1], ops[(nil)], fde[0x8919578], ldap[0x81accc0]
(Sat Dec 11 14:12:45 2010) [sssd[be[redhat.com]]] [fo_set_port_status] (4): Marking port 389 of server 'ldap.bos.redhat.com' as 'not working'
(Sat Dec 11 14:12:45 2010) [sssd[be[redhat.com]]] [sdap_handle_release] (8): Trace: sh[0x8914460], connected[1], ops[(nil)], ldap[0x81accc0], destructor_lock[0], release_memory[0]
(Sat Dec 11 14:12:45 2010) [sssd[be[redhat.com]]] [fo_resolve_service_send] (4): Trying to resolve service 'LDAP'
(Sat Dec 11 14:12:45 2010) [sssd[be[redhat.com]]] [get_server_status] (7): Status of server 'ldap.bos.redhat.com' is 'working'
(Sat Dec 11 14:12:45 2010) [sssd[be[redhat.com]]] [get_port_status] (7): Port status of port 389 for server 'ldap.bos.redhat.com' is 'not working'
(Sat Dec 11 14:12:45 2010) [sssd[be[redhat.com]]] [fo_resolve_service_send] (1): No available servers for service 'LDAP'
(Sat Dec 11 14:12:45 2010) [sssd[be[redhat.com]]] [sdap_id_op_connect_done] (1): Failed to connect, going offline (5 [Input/output error])
(Sat Dec 11 14:12:45 2010) [sssd[be[redhat.com]]] [be_mark_offline] (8): Going offline!
(Sat Dec 11 14:12:45 2010) [sssd[be[redhat.com]]] [be_run_offline_cb] (3): Going offline. Running callbacks.
(Sat Dec 11 14:12:45 2010) [sssd[be[redhat.com]]] [sdap_id_op_connect_done] (9): notify offline to op #1
(Sat Dec 11 14:12:45 2010) [sssd[be[redhat.com]]] [sdap_id_release_conn_data] (9): releasing unused connection
(Sat Dec 11 14:12:45 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:12:45 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:12:45 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:12:45 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:12:45 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:12:46 2010) [sssd[be[redhat.com]]] [remove_krb5_info_files] (5): Could not remove [/var/lib/sss/pubconf/kdcinfo.REDHAT.COM], [2][No such file or directory]
(Sat Dec 11 14:12:46 2010) [sssd[be[redhat.com]]] [remove_krb5_info_files] (5): Could not remove [/var/lib/sss/pubconf/kpasswdinfo.REDHAT.COM], [2][No such file or directory]
(Sat Dec 11 14:15:17 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:15:17 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:15:17 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:15:17 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:15:17 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:15:17 2010) [sssd[be[redhat.com]]] [sdap_id_op_connect_step] (9): beginning to connect
(Sat Dec 11 14:15:17 2010) [sssd[be[redhat.com]]] [fo_resolve_service_send] (4): Trying to resolve service 'LDAP'
(Sat Dec 11 14:15:17 2010) [sssd[be[redhat.com]]] [get_server_status] (7): Status of server 'ldap.bos.redhat.com' is 'working'
(Sat Dec 11 14:15:17 2010) [sssd[be[redhat.com]]] [get_port_status] (7): Port status of port 389 for server 'ldap.bos.redhat.com' is 'not working'
(Sat Dec 11 14:15:17 2010) [sssd[be[redhat.com]]] [get_port_status] (4): Reseting the status of port 389 for server 'ldap.bos.redhat.com'
(Sat Dec 11 14:15:17 2010) [sssd[be[redhat.com]]] [get_server_status] (7): Status of server 'ldap.bos.redhat.com' is 'working'
(Sat Dec 11 14:15:17 2010) [sssd[be[redhat.com]]] [be_resolve_server_done] (4): Found address for server ldap.bos.redhat.com: [10.16.255.3]
(Sat Dec 11 14:15:17 2010) [sssd[be[redhat.com]]] [setup_ldap_connection_callbacks] (9): LDAP connection callbacks are not supported.
(Sat Dec 11 14:15:17 2010) [sssd[be[redhat.com]]] [sdap_get_rootdse_send] (9): Getting rootdse
(Sat Dec 11 14:15:17 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (6): calling ldap_search_ext with [(objectclass=)][].
(Sat Dec 11 14:15:17 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: []
(Sat Dec 11 14:15:17 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [altServer]
(Sat Dec 11 14:15:17 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [namingContexts]
(Sat Dec 11 14:15:17 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedControl]
(Sat Dec 11 14:15:17 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedExtension]
(Sat Dec 11 14:15:17 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedFeatures]
(Sat Dec 11 14:15:17 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedLDAPVersion]
(Sat Dec 11 14:15:17 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedSASLMechanisms]
(Sat Dec 11 14:15:17 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [defaultNamingContext]
(Sat Dec 11 14:15:17 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [lastUSN]
(Sat Dec 11 14:15:17 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [highestCommittedUSN]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (3): ldap_search_ext failed: Can't contact LDAP server
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (3): Connection error: (null)
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sdap_install_ldap_callbacks] (8): Trace: sh[0x89144b0], connected[1], ops[(nil)], fde[0x89167e0], ldap[0x81accc0]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [fo_set_port_status] (4): Marking port 389 of server 'ldap.bos.redhat.com' as 'not working'
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sdap_handle_release] (8): Trace: sh[0x89144b0], connected[1], ops[(nil)], ldap[0x81accc0], destructor_lock[0], release_memory[0]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [fo_resolve_service_send] (4): Trying to resolve service 'LDAP'
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [get_server_status] (7): Status of server 'ldap.bos.redhat.com' is 'working'
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [get_port_status] (7): Port status of port 389 for server 'ldap.bos.redhat.com' is 'not working'
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [fo_resolve_service_send] (1): No available servers for service 'LDAP'
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sdap_id_op_connect_done] (1): Failed to connect, going offline (5 [Input/output error])
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_mark_offline] (8): Going offline!
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_run_offline_cb] (3): Going offline. Running callbacks.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sdap_id_op_connect_done] (9): notify offline to op #1
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sdap_id_release_conn_data] (9): releasing unused connection
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][name=dpal]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [remove_krb5_info_files] (5): Could not remove [/var/lib/sss/pubconf/kdcinfo.REDHAT.COM], [2][No such file or directory]
(Sat Dec 11 14:16:37 2010) [sssd[be[redhat.com]]] [remove_krb5_info_files] (5): Could not remove [/var/lib/sss/pubconf/kpasswdinfo.REDHAT.COM], [2][No such file or directory]
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:48 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4099][1][name=dpal]
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4099][1][name=dpal]
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4099][1][name=dpal]
(Sat Dec 11 14:16:50 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:17:11 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:17:11 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:17:11 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:17:11 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:17:11 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:18:00 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:18:00 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:18:00 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:18:00 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:18:00 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:18:00 2010) [sssd[be[redhat.com]]] [sdap_id_op_connect_step] (9): beginning to connect
(Sat Dec 11 14:18:00 2010) [sssd[be[redhat.com]]] [fo_resolve_service_send] (4): Trying to resolve service 'LDAP'
(Sat Dec 11 14:18:00 2010) [sssd[be[redhat.com]]] [get_server_status] (7): Status of server 'ldap.bos.redhat.com' is 'working'
(Sat Dec 11 14:18:00 2010) [sssd[be[redhat.com]]] [get_port_status] (7): Port status of port 389 for server 'ldap.bos.redhat.com' is 'not working'
(Sat Dec 11 14:18:00 2010) [sssd[be[redhat.com]]] [get_port_status] (4): Reseting the status of port 389 for server 'ldap.bos.redhat.com'
(Sat Dec 11 14:18:00 2010) [sssd[be[redhat.com]]] [get_server_status] (7): Status of server 'ldap.bos.redhat.com' is 'working'
(Sat Dec 11 14:18:00 2010) [sssd[be[redhat.com]]] [be_resolve_server_done] (4): Found address for server ldap.bos.redhat.com: [10.16.255.3]
(Sat Dec 11 14:18:00 2010) [sssd[be[redhat.com]]] [setup_ldap_connection_callbacks] (9): LDAP connection callbacks are not supported.
(Sat Dec 11 14:18:00 2010) [sssd[be[redhat.com]]] [sdap_get_rootdse_send] (9): Getting rootdse
(Sat Dec 11 14:18:00 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (6): calling ldap_search_ext with [(objectclass=)][].
(Sat Dec 11 14:18:00 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: []
(Sat Dec 11 14:18:00 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [altServer]
(Sat Dec 11 14:18:00 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [namingContexts]
(Sat Dec 11 14:18:00 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedControl]
(Sat Dec 11 14:18:00 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedExtension]
(Sat Dec 11 14:18:00 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedFeatures]
(Sat Dec 11 14:18:00 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedLDAPVersion]
(Sat Dec 11 14:18:00 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedSASLMechanisms]
(Sat Dec 11 14:18:00 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [defaultNamingContext]
(Sat Dec 11 14:18:00 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [lastUSN]
(Sat Dec 11 14:18:00 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (7): Requesting attrs: [highestCommittedUSN]
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (3): ldap_search_ext failed: Can't contact LDAP server
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [sdap_get_generic_send] (3): Connection error: (null)
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [sdap_install_ldap_callbacks] (8): Trace: sh[0x81acd40], connected[1], ops[(nil)], fde[0x89167e0], ldap[0x8919680]
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [fo_set_port_status] (4): Marking port 389 of server 'ldap.bos.redhat.com' as 'not working'
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [sdap_handle_release] (8): Trace: sh[0x81acd40], connected[1], ops[(nil)], ldap[0x8919680], destructor_lock[0], release_memory[0]
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [fo_resolve_service_send] (4): Trying to resolve service 'LDAP'
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [get_server_status] (7): Status of server 'ldap.bos.redhat.com' is 'working'
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [get_port_status] (7): Port status of port 389 for server 'ldap.bos.redhat.com' is 'not working'
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [fo_resolve_service_send] (1): No available servers for service 'LDAP'
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [sdap_id_op_connect_done] (1): Failed to connect, going offline (5 [Input/output error])
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [be_mark_offline] (8): Going offline!
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [be_run_offline_cb] (3): Going offline. Running callbacks.
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [sdap_id_op_connect_done] (9): notify offline to op #1
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [sdap_id_release_conn_data] (9): releasing unused connection
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:19:20 2010) [sssd[be[redhat.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:19:21 2010) [sssd[be[redhat.com]]] [remove_krb5_info_files] (5): Could not remove [/var/lib/sss/pubconf/kdcinfo.REDHAT.COM], [2][No such file or directory]
(Sat Dec 11 14:19:21 2010) [sssd[be[redhat.com]]] [remove_krb5_info_files] (5): Could not remove [/var/lib/sss/pubconf/kpasswdinfo.REDHAT.COM], [2][No such file or directory]

}}} => Was working semi-offline i.e. had internet connection but not VPN.

Every several minutes SSSD would block to try to establish LDAP connection and wait for 80 seconds:

{{{
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_id_op_connect_step] (9): beginning to connect
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [fo_resolve_service_send] (4): Trying to resolve service 'LDAP'
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [get_server_status] (7): Status of server 'ldap.baz.foobar.com' is 'working'
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [get_port_status] (7): Port status of port 389 for server 'ldap.baz.foobar.com' is 'not working'
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [get_port_status] (4): Reseting the status of port 389 for server 'ldap.baz.foobar.com'
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [get_server_status] (7): Status of server 'ldap.baz.foobar.com' is 'working'
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [be_resolve_server_done] (4): Found address for server ldap.baz.foobar.com: [xxx.xxx.xxx.xxx]
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [setup_ldap_connection_callbacks] (9): LDAP connection callbacks are not supported.
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_get_rootdse_send] (9): Getting rootdse
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (6): calling ldap_search_ext with [(objectclass=)][].
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: []
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [altServer]
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [namingContexts]
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedControl]
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedExtension]
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedFeatures]
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedLDAPVersion]
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedSASLMechanisms]
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [defaultNamingContext]
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [lastUSN]
(Sat Dec 11 14:11:25 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [highestCommittedUSN]

<====== Here! It is 80 sec timeout (see more of those repeating below =======>

(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (3): ldap_search_ext failed: Can't contact LDAP server
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (3): Connection error: (null)
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [sdap_install_ldap_callbacks] (8): Trace: sh[0x8914460], connected[1], ops[(nil)], fde[0x8919578], ldap[0x81accc0]
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [fo_set_port_status] (4): Marking port 389 of server 'ldap.baz.foobar.com' as 'not working'
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [sdap_handle_release] (8): Trace: sh[0x8914460], connected[1], ops[(nil)], ldap[0x81accc0], destructor_lock[0], release_memory[0]
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [fo_resolve_service_send] (4): Trying to resolve service 'LDAP'
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [get_server_status] (7): Status of server 'ldap.baz.foobar.com' is 'working'
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [get_port_status] (7): Port status of port 389 for server 'ldap.baz.foobar.com' is 'not working'
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [fo_resolve_service_send] (1): No available servers for service 'LDAP'
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [sdap_id_op_connect_done] (1): Failed to connect, going offline (5 [Input/output error])
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [be_mark_offline] (8): Going offline!
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [be_run_offline_cb] (3): Going offline. Running callbacks.
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [sdap_id_op_connect_done] (9): notify offline to op #1
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [sdap_id_release_conn_data] (9): releasing unused connection
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:12:45 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:12:46 2010) [sssd[be[foobar.com]]] [remove_krb5_info_files] (5): Could not remove [/var/lib/sss/pubconf/kdcinfo.FOOBAR.COM], [2][No such file or directory]
(Sat Dec 11 14:12:46 2010) [sssd[be[foobar.com]]] [remove_krb5_info_files] (5): Could not remove [/var/lib/sss/pubconf/kpasswdinfo.FOOBAR.COM], [2][No such file or directory]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_id_op_connect_step] (9): beginning to connect
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [fo_resolve_service_send] (4): Trying to resolve service 'LDAP'
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [get_server_status] (7): Status of server 'ldap.baz.foobar.com' is 'working'
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [get_port_status] (7): Port status of port 389 for server 'ldap.baz.foobar.com' is 'not working'
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [get_port_status] (4): Reseting the status of port 389 for server 'ldap.baz.foobar.com'
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [get_server_status] (7): Status of server 'ldap.baz.foobar.com' is 'working'
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [be_resolve_server_done] (4): Found address for server ldap.baz.foobar.com: [xxx.xxx.xxx.xxx]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [setup_ldap_connection_callbacks] (9): LDAP connection callbacks are not supported.
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_get_rootdse_send] (9): Getting rootdse
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (6): calling ldap_search_ext with [(objectclass=)][].
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: []
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [altServer]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [namingContexts]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedControl]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedExtension]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedFeatures]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedLDAPVersion]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedSASLMechanisms]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [defaultNamingContext]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [lastUSN]
(Sat Dec 11 14:15:17 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [highestCommittedUSN]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (3): ldap_search_ext failed: Can't contact LDAP server
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (3): Connection error: (null)
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sdap_install_ldap_callbacks] (8): Trace: sh[0x89144b0], connected[1], ops[(nil)], fde[0x89167e0], ldap[0x81accc0]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [fo_set_port_status] (4): Marking port 389 of server 'ldap.baz.foobar.com' as 'not working'
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sdap_handle_release] (8): Trace: sh[0x89144b0], connected[1], ops[(nil)], ldap[0x81accc0], destructor_lock[0], release_memory[0]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [fo_resolve_service_send] (4): Trying to resolve service 'LDAP'
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [get_server_status] (7): Status of server 'ldap.baz.foobar.com' is 'working'
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [get_port_status] (7): Port status of port 389 for server 'ldap.baz.foobar.com' is 'not working'
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [fo_resolve_service_send] (1): No available servers for service 'LDAP'
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sdap_id_op_connect_done] (1): Failed to connect, going offline (5 [Input/output error])
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_mark_offline] (8): Going offline!
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_run_offline_cb] (3): Going offline. Running callbacks.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sdap_id_op_connect_done] (9): notify offline to op #1
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sdap_id_release_conn_data] (9): releasing unused connection
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][name=dpal]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [remove_krb5_info_files] (5): Could not remove [/var/lib/sss/pubconf/kdcinfo.FOOBAR.COM], [2][No such file or directory]
(Sat Dec 11 14:16:37 2010) [sssd[be[foobar.com]]] [remove_krb5_info_files] (5): Could not remove [/var/lib/sss/pubconf/kpasswdinfo.FOOBAR.COM], [2][No such file or directory]
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:48 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4099][1][name=dpal]
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4099][1][name=dpal]
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4099][1][name=dpal]
(Sat Dec 11 14:16:50 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:17:11 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:17:11 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:17:11 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:17:11 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:17:11 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_id_op_connect_step] (9): beginning to connect
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [fo_resolve_service_send] (4): Trying to resolve service 'LDAP'
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [get_server_status] (7): Status of server 'ldap.baz.foobar.com' is 'working'
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [get_port_status] (7): Port status of port 389 for server 'ldap.baz.foobar.com' is 'not working'
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [get_port_status] (4): Reseting the status of port 389 for server 'ldap.baz.foobar.com'
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [get_server_status] (7): Status of server 'ldap.baz.foobar.com' is 'working'
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [be_resolve_server_done] (4): Found address for server ldap.baz.foobar.com: [xxx.xxx.xxx.xxx]
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [setup_ldap_connection_callbacks] (9): LDAP connection callbacks are not supported.
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_get_rootdse_send] (9): Getting rootdse
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (6): calling ldap_search_ext with [(objectclass=)][].
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: []
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [altServer]
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [namingContexts]
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedControl]
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedExtension]
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedFeatures]
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedLDAPVersion]
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [supportedSASLMechanisms]
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [defaultNamingContext]
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [lastUSN]
(Sat Dec 11 14:18:00 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (7): Requesting attrs: [highestCommittedUSN]
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (3): ldap_search_ext failed: Can't contact LDAP server
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sdap_get_generic_send] (3): Connection error: (null)
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sdap_install_ldap_callbacks] (8): Trace: sh[0x81acd40], connected[1], ops[(nil)], fde[0x89167e0], ldap[0x8919680]
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [fo_set_port_status] (4): Marking port 389 of server 'ldap.baz.foobar.com' as 'not working'
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sdap_handle_release] (8): Trace: sh[0x81acd40], connected[1], ops[(nil)], ldap[0x8919680], destructor_lock[0], release_memory[0]
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [fo_resolve_service_send] (4): Trying to resolve service 'LDAP'
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [get_server_status] (7): Status of server 'ldap.baz.foobar.com' is 'working'
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [get_port_status] (7): Port status of port 389 for server 'ldap.baz.foobar.com' is 'not working'
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [fo_resolve_service_send] (1): No available servers for service 'LDAP'
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sdap_id_op_connect_done] (1): Failed to connect, going offline (5 [Input/output error])
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [be_mark_offline] (8): Going offline!
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [be_run_offline_cb] (3): Going offline. Running callbacks.
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sdap_id_op_connect_done] (9): notify offline to op #1
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sdap_id_release_conn_data] (9): releasing unused connection
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): dbus conn: 817EF68
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sbus_dispatch] (9): Dispatching.
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo]
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Got request for [4097][1][idnumber=12785]
(Sat Dec 11 14:19:20 2010) [sssd[be[foobar.com]]] [be_get_account_info] (4): Request processed. Returned 1,11,Fast reply - offline
(Sat Dec 11 14:19:21 2010) [sssd[be[foobar.com]]] [remove_krb5_info_files] (5): Could not remove [/var/lib/sss/pubconf/kdcinfo.FOOBAR.COM], [2][No such file or directory]
(Sat Dec 11 14:19:21 2010) [sssd[be[foobar.com]]] [remove_krb5_info_files] (5): Could not remove [/var/lib/sss/pubconf/kpasswdinfo.FOOBAR.COM], [2][No such file or directory]

}}}

Comment from sbose at 2010-12-13 09:17:57

I'm pretty sure that this is the same issue as #664

Comment from sgallagh at 2010-12-14 15:33:58

Fields changed

milestone: NEEDS_TRIAGE => SSSD 1.5.1

Comment from sgallagh at 2011-01-04 15:29:13

Fields changed

component: SSSD => LDAP Provider
coverity: =>
owner: somebody => sbose

Comment from sgallagh at 2011-01-27 19:14:52

Fields changed

milestone: SSSD 1.5.1 => SSSD 1.5.2
upgrade: => 0

Comment from dpal at 2011-02-03 15:11:44

Fields changed

resolution: => worksforme
status: new => closed

Comment from dpal at 2012-01-19 03:08:07

Fields changed

rhbz: => 0

Comment from dpal at 2017-02-24 14:29:07

Metadata Update from @dpal:

  • Issue assigned to sbose
  • Issue set to the milestone: SSSD 1.5.2
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees

@sumit-bose sumit-bose

Labels
Closed: Not a bug
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
2 participants
@sumit-bose @sssd-bot