Ticket #709 (closed defect: worksforme)

Opened 3 years ago

Last modified 2 years ago

SSSD blocks for 80 seconds trying to connect to LDAP

Reported by: dpal Owned by: sbose
Priority: major Milestone: SSSD 1.5.2
Component: LDAP Provider Version: 1.4.1
Keywords: Cc:
Blocked By: Blocking:
Tests Updated: no Coverity Bug:
Patch Submitted: Red Hat Bugzilla: 0
Design link:
Feature Milestone:
Design review: Fedora test page:
Chosen: Candidate to push out:
Release Notes:

Description (last modified by dpal) (diff)

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]

Change History

comment:1 Changed 3 years ago by dpal

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

comment:2 Changed 3 years ago by dpal

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

comment:3 Changed 3 years ago by dpal

  • Description modified (diff)

comment:4 Changed 3 years ago by sbose

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

comment:5 Changed 3 years ago by sgallagh

  • Milestone changed from NEEDS_TRIAGE to SSSD 1.5.1

comment:6 Changed 3 years ago by sgallagh

  • Component changed from SSSD to LDAP Provider
  • Owner changed from somebody to sbose

comment:7 Changed 3 years ago by sgallagh

  • upgrade set to 0
  • Milestone changed from SSSD 1.5.1 to SSSD 1.5.2

comment:8 Changed 3 years ago by dpal

  • Status changed from new to closed
  • Resolution set to worksforme

comment:9 Changed 2 years ago by dpal

  • Red Hat Bugzilla set to 0
Note: See TracTickets for help on using tickets.