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

Created 6 years ago by dpal
Modified 4 months ago

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]

I am using latest 1.5 scratch build: 0 2010120820git583a018.el5

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

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]

}}}

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

Fields changed

milestone: NEEDS_TRIAGE => SSSD 1.5.1

Fields changed

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

Fields changed

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

Fields changed

resolution: => worksforme
status: new => closed

Fields changed

rhbz: => 0

4 months ago

Metadata Update from @dpal:
- Issue assigned to sbose
- Issue set to the milestone: SSSD 1.5.2

Login to comment on this ticket.

defect

LDAP Provider

1.4.1

0

0

cancel