Setup : FreeIPA server 4.1.2 on CentOS 7. FreeIPA client on CentOS 5.11 Client installed properly with the exception of the following error about updating A records (from ipaclient-install.log)
2015-09-25 12:24:23,195 DEBUG Writing nsupdate commands to /etc/ipa/.dns_update.txt: zone ipadomain.net. update delete msghub4.ipadomain.net. IN A send update add msghub4.ipadomain.net. 1200 IN A 10.21.5.215 send 2015-09-25 12:24:29,500 DEBUG args=/usr/bin/nsupdate -g /etc/ipa/.dns_update.txt 2015-09-25 12:24:29,500 DEBUG stdout= 2015-09-25 12:24:29,500 DEBUG stderr=mem.c:877: INSIST(ctx->stats[i].gets == 0U) failed. After I checked DNS though and confirmed that the A entry existed and it did. Also reverse entry was correct. Also, time on server is correct. Also, there are no strange entries in the hosts file. I know that CentOS 5.11 works with FreeIPA because I have another 5.11 machine with ssh and sudo both working using ipa/ldap. However, the big problem is that I cannot login with ipa users on this one machine. From the client : [root@msghub4 ipa]# kinit username Password for usern...@ipadomain.net: [root@msghub4 ipa]# klist Ticket cache: FILE:/tmp/krb5cc_0 Default principal: usern...@ipadomain.net Valid starting Expires Service principal 09/25/15 16:41:51 09/26/15 16:41:48 krbtgt/ipadomain....@ipadomain.net Kerberos 4 ticket cache: /tmp/tkt0 klist: You have no tickets cached When I enable logging in the sssd I get the following in the krb5_child.log: (Fri Sep 25 16:23:55 2015) [[sssd[krb5_child[11095]]]] [krb5_child_setup] (7): Cannot read [SSSD_KRB5_RENEWABLE_LIFETIME] from environment. (Fri Sep 25 16:23:55 2015) [[sssd[krb5_child[11095]]]] [krb5_child_setup] (7): Cannot read [SSSD_KRB5_LIFETIME] from environment. (Fri Sep 25 16:23:55 2015) [[sssd[krb5_child[11095]]]] [krb5_child_setup] (9): Not using FAST. (Fri Sep 25 16:23:55 2015) [[sssd[krb5_child[11095]]]] [sss_krb5_get_init_creds_opt_set_expire_callback] (5): krb5_get_init_creds_opt_set_expire_callback not available. (Fri Sep 25 16:23:55 2015) [[sssd[krb5_child[11095]]]] [get_and_save_tgt] (1): 721: [-1765328353][Decrypt integrity check failed] (Fri Sep 25 16:23:55 2015) [[sssd[krb5_child[11095]]]] [tgt_req_child] (1): 980: [-1765328353][Decrypt integrity check failed] According to every google search I've done, "decrypt integrity check failed" means bad password. I know this is wrong because I can login to 200 other machines in this domain with password or kerberos and my password does not expire for 10 years. Here is my sssd.log : (Fri Sep 25 16:46:30 2015) [sssd] [sbus_dispatch] (9): Dispatching. (Fri Sep 25 16:46:30 2015) [sssd] [ping_check] (4): Service ipadomain.net replied to ping (Fri Sep 25 16:46:30 2015) [sssd] [sbus_remove_timeout] (8): 0xed6dfb0 (Fri Sep 25 16:46:30 2015) [sssd] [sbus_dispatch] (9): dbus conn: ED6EE00 (Fri Sep 25 16:46:30 2015) [sssd] [sbus_dispatch] (9): Dispatching. (Fri Sep 25 16:46:30 2015) [sssd] [ping_check] (4): Service nss replied to ping (Fri Sep 25 16:46:30 2015) [sssd] [sbus_remove_timeout] (8): 0xed75e80 (Fri Sep 25 16:46:30 2015) [sssd] [sbus_dispatch] (9): dbus conn: ED6CD00 (Fri Sep 25 16:46:30 2015) [sssd] [sbus_dispatch] (9): Dispatching. (Fri Sep 25 16:46:30 2015) [sssd] [ping_check] (4): Service pam replied to ping (Fri Sep 25 16:46:39 2015) [sssd] [service_send_ping] (4): Pinging ipadomain.net (Fri Sep 25 16:46:40 2015) [sssd] [sbus_add_timeout] (8): 0xed75e80 (Fri Sep 25 16:46:40 2015) [sssd] [service_send_ping] (4): Pinging nss (Fri Sep 25 16:46:40 2015) [sssd] [sbus_add_timeout] (8): 0xed6dfb0 (Fri Sep 25 16:46:40 2015) [sssd] [service_send_ping] (4): Pinging pam (Fri Sep 25 16:46:40 2015) [sssd] [sbus_add_timeout] (8): 0xed66930 (Fri Sep 25 16:46:40 2015) [sssd] [sbus_remove_timeout] (8): 0xed75e80 (Fri Sep 25 16:46:40 2015) [sssd] [sbus_dispatch] (9): dbus conn: ED6A1B0 (Fri Sep 25 16:46:40 2015) [sssd] [sbus_dispatch] (9): Dispatching. (Fri Sep 25 16:46:40 2015) [sssd] [ping_check] (4): Service ipadomain.net replied to ping (Fri Sep 25 16:46:40 2015) [sssd] [sbus_remove_timeout] (8): 0xed6dfb0 (Fri Sep 25 16:46:40 2015) [sssd] [sbus_dispatch] (9): dbus conn: ED6EE00 (Fri Sep 25 16:46:40 2015) [sssd] [sbus_dispatch] (9): Dispatching. (Fri Sep 25 16:46:40 2015) [sssd] [ping_check] (4): Service nss replied to ping (Fri Sep 25 16:46:40 2015) [sssd] [sbus_remove_timeout] (8): 0xed66930 (Fri Sep 25 16:46:40 2015) [sssd] [sbus_dispatch] (9): dbus conn: ED6CD00 (Fri Sep 25 16:46:40 2015) [sssd] [sbus_dispatch] (9): Dispatching. (Fri Sep 25 16:46:40 2015) [sssd] [ping_check] (4): Service pam replied to ping (Fri Sep 25 16:46:49 2015) [sssd] [service_send_ping] (4): Pinging ipadomain.net (Fri Sep 25 16:46:49 2015) [sssd] [sbus_add_timeout] (8): 0xed66930 (Fri Sep 25 16:46:49 2015) [sssd] [service_send_ping] (4): Pinging nss (Fri Sep 25 16:46:49 2015) [sssd] [sbus_add_timeout] (8): 0xed6dfb0 (Fri Sep 25 16:46:49 2015) [sssd] [service_send_ping] (4): Pinging pam (Fri Sep 25 16:46:49 2015) [sssd] [sbus_add_timeout] (8): 0xed75e80 (Fri Sep 25 16:46:50 2015) [sssd] [sbus_remove_timeout] (8): 0xed66930 (Fri Sep 25 16:46:50 2015) [sssd] [sbus_dispatch] (9): dbus conn: ED6A1B0 (Fri Sep 25 16:46:50 2015) [sssd] [sbus_dispatch] (9): Dispatching. (Fri Sep 25 16:46:50 2015) [sssd] [ping_check] (4): Service ipadomain.net replied to ping (Fri Sep 25 16:46:50 2015) [sssd] [sbus_remove_timeout] (8): 0xed6dfb0 (Fri Sep 25 16:46:50 2015) [sssd] [sbus_dispatch] (9): dbus conn: ED6EE00 (Fri Sep 25 16:46:50 2015) [sssd] [sbus_dispatch] (9): Dispatching. (Fri Sep 25 16:46:50 2015) [sssd] [ping_check] (4): Service nss replied to ping (Fri Sep 25 16:46:50 2015) [sssd] [sbus_remove_timeout] (8): 0xed75e80 (Fri Sep 25 16:46:50 2015) [sssd] [sbus_dispatch] (9): dbus conn: ED6CD00 (Fri Sep 25 16:46:50 2015) [sssd] [sbus_dispatch] (9): Dispatching. (Fri Sep 25 16:46:50 2015) [sssd] [ping_check] (4): Service pam replied to ping (Fri Sep 25 16:46:59 2015) [sssd] [service_send_ping] (4): Pinging ipadomain.net (Fri Sep 25 16:46:59 2015) [sssd] [sbus_add_timeout] (8): 0xed75e80 (Fri Sep 25 16:46:59 2015) [sssd] [service_send_ping] (4): Pinging nss (Fri Sep 25 16:46:59 2015) [sssd] [sbus_add_timeout] (8): 0xed6dfb0 (Fri Sep 25 16:46:59 2015) [sssd] [service_send_ping] (4): Pinging pam (Fri Sep 25 16:46:59 2015) [sssd] [sbus_add_timeout] (8): 0xed66930 (Fri Sep 25 16:47:00 2015) [sssd] [sbus_remove_timeout] (8): 0xed75e80 (Fri Sep 25 16:47:00 2015) [sssd] [sbus_dispatch] (9): dbus conn: ED6A1B0 (Fri Sep 25 16:47:00 2015) [sssd] [sbus_dispatch] (9): Dispatching. (Fri Sep 25 16:47:00 2015) [sssd] [ping_check] (4): Service ipadomain.net replied to ping (Fri Sep 25 16:47:00 2015) [sssd] [sbus_remove_timeout] (8): 0xed6dfb0 (Fri Sep 25 16:47:00 2015) [sssd] [sbus_dispatch] (9): dbus conn: ED6EE00 (Fri Sep 25 16:47:00 2015) [sssd] [sbus_dispatch] (9): Dispatching. (Fri Sep 25 16:47:00 2015) [sssd] [ping_check] (4): Service nss replied to ping (Fri Sep 25 16:47:00 2015) [sssd] [sbus_remove_timeout] (8): 0xed66930 (Fri Sep 25 16:47:00 2015) [sssd] [sbus_dispatch] (9): dbus conn: ED6CD00 (Fri Sep 25 16:47:00 2015) [sssd] [sbus_dispatch] (9): Dispatching. (Fri Sep 25 16:47:00 2015) [sssd] [ping_check] (4): Service pam replied to ping (Fri Sep 25 16:47:10 2015) [sssd] [service_send_ping] (4): Pinging ipadomain.net (Fri Sep 25 16:47:10 2015) [sssd] [sbus_add_timeout] (8): 0xed66930 (Fri Sep 25 16:47:10 2015) [sssd] [service_send_ping] (4): Pinging nss (Fri Sep 25 16:47:10 2015) [sssd] [sbus_add_timeout] (8): 0xed6dfb0 (Fri Sep 25 16:47:10 2015) [sssd] [service_send_ping] (4): Pinging pam (Fri Sep 25 16:47:10 2015) [sssd] [sbus_add_timeout] (8): 0xed75e80 (Fri Sep 25 16:47:10 2015) [sssd] [sbus_remove_timeout] (8): 0xed66930 (Fri Sep 25 16:47:10 2015) [sssd] [sbus_dispatch] (9): dbus conn: ED6A1B0 (Fri Sep 25 16:47:10 2015) [sssd] [sbus_dispatch] (9): Dispatching. (Fri Sep 25 16:47:10 2015) [sssd] [ping_check] (4): Service ipadomain.net replied to ping (Fri Sep 25 16:47:10 2015) [sssd] [sbus_remove_timeout] (8): 0xed6dfb0 (Fri Sep 25 16:47:10 2015) [sssd] [sbus_dispatch] (9): dbus conn: ED6EE00 (Fri Sep 25 16:47:10 2015) [sssd] [sbus_dispatch] (9): Dispatching. (Fri Sep 25 16:47:10 2015) [sssd] [ping_check] (4): Service nss replied to ping (Fri Sep 25 16:47:10 2015) [sssd] [sbus_remove_timeout] (8): 0xed75e80 (Fri Sep 25 16:47:10 2015) [sssd] [sbus_dispatch] (9): dbus conn: ED6CD00 (Fri Sep 25 16:47:10 2015) [sssd] [sbus_dispatch] (9): Dispatching. (Fri Sep 25 16:47:10 2015) [sssd] [ping_check] (4): Service pam replied to ping Here is the contents of my sss_mydomain.net.log : (Fri Sep 25 16:47:49 2015) [sssd[be[ipadomain.net]]] [sbus_message_handler] (9): Received SBUS method [ping] (Fri Sep 25 16:47:59 2015) [sssd[be[ipadomain.net]]] [sbus_dispatch] (9): dbus conn: 83B4700 (Fri Sep 25 16:47:59 2015) [sssd[be[ipadomain.net]]] [sbus_dispatch] (9): Dispatching. (Fri Sep 25 16:47:59 2015) [sssd[be[ipadomain.net]]] [sbus_message_handler] (9): Received SBUS method [ping] (Fri Sep 25 16:48:10 2015) [sssd[be[ipadomain.net]]] [sbus_dispatch] (9): dbus conn: 83B4700 (Fri Sep 25 16:48:10 2015) [sssd[be[ipadomain.net]]] [sbus_dispatch] (9): Dispatching. (Fri Sep 25 16:48:10 2015) [sssd[be[ipadomain.net]]] [sbus_message_handler] (9): Received SBUS method [ping] (Fri Sep 25 16:48:19 2015) [sssd[be[ipadomain.net]]] [sbus_dispatch] (9): dbus conn: 83B4700 (Fri Sep 25 16:48:20 2015) [sssd[be[ipadomain.net]]] [sbus_dispatch] (9): Dispatching. (Fri Sep 25 16:48:20 2015) [sssd[be[ipadomain.net]]] [sbus_message_handler] (9): Received SBUS method [ping] (Fri Sep 25 16:48:29 2015) [sssd[be[ipadomain.net]]] [sbus_dispatch] (9): dbus conn: 83B4700 (Fri Sep 25 16:48:29 2015) [sssd[be[ipadomain.net]]] [sbus_dispatch] (9): Dispatching. (Fri Sep 25 16:48:29 2015) [sssd[be[ipadomain.net]]] [sbus_message_handler] (9): Received SBUS method [ping] (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sbus_dispatch] (9): dbus conn: 83C7600 (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sbus_dispatch] (9): Dispatching. (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo] (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [be_get_account_info] (4): Got request for [4098][1][idnumber=756600344] (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sdap_id_op_connect_step] (9): reusing cached connection (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(gidNumber=756600344)(objectclass=posixGroup)(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][cn=accounts,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 121 (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83e06e0], ldap[0x83d0370] (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sdap_parse_entry] (9): OriginalDN: [cn=username,cn=groups,cn=accounts,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83e06e0], ldap[0x83d0370] (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sdap_get_groups_process] (6): Search for groups, returned 1 results. (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 0) (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sdap_save_group] (8): This is a posix group (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sdap_save_group] (7): Adding original DN [cn=username,cn=groups,cn=accounts,dc=ipadomain,dc=net] to attributes of [username]. (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sdap_save_group] (6): Storing info for group username (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_callback": 0x83da130 (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_timeout": 0x83da250 (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Running timer event 0x83da130 "ltdb_callback" (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Destroying timer event 0x83da250 "ltdb_timeout" (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Ending timer event 0x83da130 "ltdb_callback" (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 1) (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_callback": 0x83e26e0 (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_timeout": 0x83e2800 (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Running timer event 0x83e26e0 "ltdb_callback" (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Destroying timer event 0x83e2800 "ltdb_timeout" (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Ending timer event 0x83e26e0 "ltdb_callback" (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [ldb] (9): commit ldb transaction (nesting: 1) (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sdap_save_groups] (9): Group 0 processed! (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sdap_save_grpmem] (7): No members for group [username] (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sdap_save_grpmem] (6): Storing members for group username (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_callback": 0x83e2b00 (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_timeout": 0x83e91d0 (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Running timer event 0x83e2b00 "ltdb_callback" (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Destroying timer event 0x83e91d0 "ltdb_timeout" (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Ending timer event 0x83e2b00 "ltdb_callback" (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 1) (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_callback": 0x83cd9d0 (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_timeout": 0x83cda80 (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Running timer event 0x83cd9d0 "ltdb_callback" (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Destroying timer event 0x83cda80 "ltdb_timeout" (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Ending timer event 0x83cd9d0 "ltdb_callback" (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [ldb] (9): commit ldb transaction (nesting: 1) (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sdap_save_groups] (9): Group 0 members processed! (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [ldb] (9): commit ldb transaction (nesting: 0) (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sdap_id_op_done] (9): releasing operation connection (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [acctinfo_callback] (4): Request processed. Returned 0,0,Success (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[(nil)], ldap[0x83d0370] (Fri Sep 25 16:48:35 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sbus_dispatch] (9): dbus conn: 83C4D10 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sbus_dispatch] (9): Dispatching. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [be_get_account_info] (4): Got request for [3][1][name=username] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_id_op_connect_step] (9): reusing cached connection (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_initgr_send] (9): Retrieving info for initgroups call (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(uid=username)(objectclass=posixAccount))][cn=accounts,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [uid] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [uidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gecos] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [homeDirectory] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [loginShell] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [krbPrincipalName] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [memberOf] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [shadowLastChange] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [shadowMin] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [shadowMax] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [shadowWarning] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [shadowInactive] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [shadowExpire] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [shadowFlag] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [krbLastPwdChange] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [krbPasswordExpiration] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [pwdAttribute] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [authorizedService] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [accountExpires] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userAccountControl] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsAccountLock] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 122 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83e0790], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_parse_entry] (9): OriginalDN: [uid=username,cn=users,cn=accounts,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83e0790], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_initgr_user] (9): Receiving info for the user (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 0) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_initgr_user] (9): Storing the user (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_save_user] (9): Save user (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_save_user] (7): Adding original DN [uid=username,cn=users,cn=accounts,dc=ipadomain,dc=net] to attributes of [username]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_save_user] (7): Adding original memberOf attributes to [username]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_save_user] (7): Adding user principal [usern...@ipadomain.net] to attributes of [username]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_save_user] (9): Adding [krbLastPwdChange]=[20150525212313Z] to user attributes. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_save_user] (9): Adding [krbPasswordExpiration]=[20250522212313Z] to user attributes. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_save_user] (6): Storing info for user username (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 1) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_callback": 0x83ea220 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_timeout": 0x83ea340 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Running timer event 0x83ea220 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Destroying timer event 0x83ea340 "ltdb_timeout" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Ending timer event 0x83ea220 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 2) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_callback": 0x83fbd60 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_timeout": 0x83fbe80 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Running timer event 0x83fbd60 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Destroying timer event 0x83fbe80 "ltdb_timeout" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Ending timer event 0x83fbd60 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): commit ldb transaction (nesting: 2) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 2) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sysdb_remove_attrs] (8): Removing attribute [userPassword] from [username] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_callback": 0x83eab10 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_timeout": 0x83eac30 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Running timer event 0x83eab10 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Destroying timer event 0x83eac30 "ltdb_timeout" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Ending timer event 0x83eab10 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): cancel ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sysdb_remove_attrs] (8): Removing attribute [uniqueID] from [username] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_callback": 0x8405da0 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_timeout": 0x8405ec0 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Running timer event 0x8405da0 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Destroying timer event 0x8405ec0 "ltdb_timeout" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Ending timer event 0x8405da0 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): cancel ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sysdb_remove_attrs] (8): Removing attribute [shadowLastChange] from [username] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_callback": 0x8405e40 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_timeout": 0x8405f60 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Running timer event 0x8405e40 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Destroying timer event 0x8405f60 "ltdb_timeout" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Ending timer event 0x8405e40 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): cancel ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sysdb_remove_attrs] (8): Removing attribute [shadowMin] from [username] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_callback": 0x83ead50 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_timeout": 0x83eae00 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Running timer event 0x83ead50 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Destroying timer event 0x83eae00 "ltdb_timeout" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Ending timer event 0x83ead50 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): cancel ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sysdb_remove_attrs] (8): Removing attribute [shadowMax] from [username] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_callback": 0x83eabd0 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_timeout": 0x83eacf0 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Running timer event 0x83eabd0 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Destroying timer event 0x83eacf0 "ltdb_timeout" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Ending timer event 0x83eabd0 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): cancel ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sysdb_remove_attrs] (8): Removing attribute [shadowWarning] from [username] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_callback": 0x83eab10 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_timeout": 0x83eac30 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Running timer event 0x83eab10 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Destroying timer event 0x83eac30 "ltdb_timeout" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Ending timer event 0x83eab10 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): cancel ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sysdb_remove_attrs] (8): Removing attribute [shadowInactive] from [username] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_callback": 0x8405f20 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_timeout": 0x8406040 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Running timer event 0x8405f20 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Destroying timer event 0x8406040 "ltdb_timeout" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Ending timer event 0x8405f20 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): cancel ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sysdb_remove_attrs] (8): Removing attribute [shadowExpire] from [username] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_callback": 0x83ead80 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_timeout": 0x83f65b0 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Running timer event 0x83ead80 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Destroying timer event 0x83f65b0 "ltdb_timeout" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Ending timer event 0x83ead80 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): cancel ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sysdb_remove_attrs] (8): Removing attribute [shadowFlag] from [username] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_callback": 0x83eaaa0 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_timeout": 0x83eab50 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Running timer event 0x83eaaa0 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Destroying timer event 0x83eab50 "ltdb_timeout" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Ending timer event 0x83eaaa0 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): cancel ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sysdb_remove_attrs] (8): Removing attribute [pwdAttribute] from [username] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_callback": 0x83eacb0 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_timeout": 0x83eadd0 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Running timer event 0x83eacb0 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Destroying timer event 0x83eadd0 "ltdb_timeout" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Ending timer event 0x83eacb0 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): cancel ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sysdb_remove_attrs] (8): Removing attribute [authorizedService] from [username] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_callback": 0x83f65e0 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_timeout": 0x83f6700 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Running timer event 0x83f65e0 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Destroying timer event 0x83f6700 "ltdb_timeout" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Ending timer event 0x83f65e0 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): cancel ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sysdb_remove_attrs] (8): Removing attribute [adAccountExpires] from [username] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_callback": 0x83f6650 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_timeout": 0x83f6770 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Running timer event 0x83f6650 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Destroying timer event 0x83f6770 "ltdb_timeout" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Ending timer event 0x83f6650 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): cancel ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sysdb_remove_attrs] (8): Removing attribute [adUserAccountControl] from [username] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_callback": 0x83f6710 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_timeout": 0x83f6830 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Running timer event 0x83f6710 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Destroying timer event 0x83f6830 "ltdb_timeout" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Ending timer event 0x83f6710 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): cancel ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sysdb_remove_attrs] (8): Removing attribute [nsAccountLock] from [username] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_callback": 0x83eacb0 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_timeout": 0x83f6830 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Running timer event 0x83eacb0 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Destroying timer event 0x83f6830 "ltdb_timeout" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Ending timer event 0x83eacb0 "ltdb_callback" (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): cancel ldb transaction (nesting: 3) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): commit ldb transaction (nesting: 2) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): commit ldb transaction (nesting: 1) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_initgr_user] (9): Commit change (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [ldb] (9): commit ldb transaction (nesting: 0) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_initgr_user] (9): Process user's groups (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=developers,cn=groups,cn=accounts,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 123 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee160], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee160], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=developers,cn=groups,cn=accounts,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][ipaUniqueID=8933b03e-031f-11e5-aeb6-005056b71d17,cn=hbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 124 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group ipaUniqueID=8933b03e-031f-11e5-aeb6-005056b71d17,cn=hbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][ipaUniqueID=5b317c38-04c9-11e5-b973-005056b71d17,cn=sudorules,cn=sudo,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 125 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group ipaUniqueID=5b317c38-04c9-11e5-b973-005056b71d17,cn=sudorules,cn=sudo,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=GR Read Only,cn=roles,cn=accounts,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 126 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=GR Read Only,cn=roles,cn=accounts,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=deployment_engineer,cn=groups,cn=accounts,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 127 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=deployment_engineer,cn=groups,cn=accounts,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=admins,cn=groups,cn=accounts,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 128 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_parse_entry] (9): OriginalDN: [cn=admins,cn=groups,cn=accounts,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=Replication Administrators,cn=privileges,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 129 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83edf50], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83edf50], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=Replication Administrators,cn=privileges,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=Add Replication Agreements,cn=permissions,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 130 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=Add Replication Agreements,cn=permissions,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=Modify Replication Agreements,cn=permissions,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 131 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=Modify Replication Agreements,cn=permissions,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=Remove Replication Agreements,cn=permissions,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 132 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=Remove Replication Agreements,cn=permissions,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=Modify DNA Range,cn=permissions,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 133 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=Modify DNA Range,cn=permissions,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=Read LDBM Database Configuration,cn=permissions,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 134 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=Read LDBM Database Configuration,cn=permissions,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=Read DNA Range,cn=permissions,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 135 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=Read DNA Range,cn=permissions,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=Read PassSync Managers Configuration,cn=permissions,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 136 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=Read PassSync Managers Configuration,cn=permissions,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=Modify PassSync Managers Configuration,cn=permissions,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 137 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=Modify PassSync Managers Configuration,cn=permissions,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=Add Configuration Sub-Entries,cn=permissions,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 138 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=Add Configuration Sub-Entries,cn=permissions,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=System: Read Replication Agreements,cn=permissions,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 139 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=System: Read Replication Agreements,cn=permissions,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=Host Enrollment,cn=privileges,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 140 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=Host Enrollment,cn=privileges,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=System: Add Hosts,cn=permissions,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 141 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=System: Add Hosts,cn=permissions,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=System: Add krbPrincipalName to a Host,cn=permissions,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 142 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=System: Add krbPrincipalName to a Host,cn=permissions,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=System: Enroll a Host,cn=permissions,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 143 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=System: Enroll a Host,cn=permissions,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=System: Manage Host Certificates,cn=permissions,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 144 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=System: Manage Host Certificates,cn=permissions,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=System: Manage Host Enrollment Password,cn=permissions,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 145 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=System: Manage Host Enrollment Password,cn=permissions,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=System: Manage Host Keytab,cn=permissions,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 146 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=System: Manage Host Keytab,cn=permissions,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=DNS Administrator,cn=roles,cn=accounts,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 147 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=DNS Administrator,cn=roles,cn=accounts,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=DNS Administrators,cn=privileges,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 148 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=DNS Administrators,cn=privileges,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=System: Read DNS Configuration,cn=permissions,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 149 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=System: Read DNS Configuration,cn=permissions,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=System: Write DNS Configuration,cn=permissions,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 150 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=System: Write DNS Configuration,cn=permissions,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=System: Add DNS Entries,cn=permissions,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 151 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=System: Add DNS Entries,cn=permissions,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=System: Read DNS Entries,cn=permissions,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 152 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=System: Read DNS Entries,cn=permissions,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=System: Read DNSSEC metadata,cn=permissions,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 153 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=System: Read DNSSEC metadata,cn=permissions,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=System: Remove DNS Entries,cn=permissions,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 154 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=System: Remove DNS Entries,cn=permissions,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=System: Update DNS Entries,cn=permissions,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 155 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sbus_dispatch] (9): dbus conn: 83B4700 (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sbus_dispatch] (9): Dispatching. (Fri Sep 25 16:48:39 2015) [sssd[be[ipadomain.net]]] [sbus_message_handler] (9): Received SBUS method [ping] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83ee090], ldap[0x83d0370] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=System: Update DNS Entries,cn=permissions,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][cn=System: Modify Realm Domains,cn=permissions,cn=pbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 156 (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83edf50], ldap[0x83d0370] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83edf50], ldap[0x83d0370] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group cn=System: Modify Realm Domains,cn=permissions,cn=pbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][ipaUniqueID=33381f3e-0320-11e5-8c1b-005056b71d17,cn=hbac,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 157 (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83edf50], ldap[0x83d0370] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83edf50], ldap[0x83d0370] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group ipaUniqueID=33381f3e-0320-11e5-8c1b-005056b71d17,cn=hbac,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (6): calling ldap_search_ext with [(&(objectclass=posixGroup)(cn=*))][ipaUniqueID=d79d0cfa-04b6-11e5-9f43-005056b71d17,cn=sudorules,cn=sudo,dc=ipadomain,dc=net]. (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [objectClass] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [cn] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [userPassword] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [gidNumber] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [member] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [nsUniqueId] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [modifyTimestamp] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (7): Requesting attrs: [entryUSN] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_step] (8): ldap_search_ext called, msgid = 158 (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83edf50], ldap[0x83d0370] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[0x83edf50], ldap[0x83d0370] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_search] (2): Search for group ipaUniqueID=d79d0cfa-04b6-11e5-9f43-005056b71d17,cn=sudorules,cn=sudo,dc=ipadomain,dc=net, returned 0 results. Skipping (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 0) (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 1) (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 2) (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_callback": 0x83ed490 (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_timeout": 0x83ed5b0 (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Running timer event 0x83ed490 "ltdb_callback" (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Destroying timer event 0x83ed5b0 "ltdb_timeout" (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Ending timer event 0x83ed490 "ltdb_callback" (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): commit ldb transaction (nesting: 2) (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): commit ldb transaction (nesting: 1) (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sysdb_get_direct_parents] (8): searching sysdb with filter [(&(objectClass=group)(member=name=admins,cn=groups,cn=ipadomain.net,cn=sysdb))] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_callback": 0x83ed6d0 (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_timeout": 0x83ed780 (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Running timer event 0x83ed6d0 "ltdb_callback" (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Destroying timer event 0x83ed780 "ltdb_timeout" (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Ending timer event 0x83ed6d0 "ltdb_callback" (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sysdb_get_direct_parents] (7): admins is a member of 0 sysdb groups (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_get_direct_parents] (9): Looking up direct parents for group [cn=admins,cn=groups,cn=accounts,dc=ipadomain,dc=net] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_get_direct_parents] (9): The group [cn=admins,cn=groups,cn=accounts,dc=ipadomain,dc=net] has 0 direct parents (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_nested_get_membership_diff] (7): The group admins is a direct member of 0 LDAP groups (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 1) (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 2) (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): commit ldb transaction (nesting: 2) (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): commit ldb transaction (nesting: 1) (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_store_user_memberships] (7): The user username is a direct member of 0 LDAP groups (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sysdb_get_direct_parents] (8): searching sysdb with filter [(&(objectClass=group)(member=name=username,cn=users,cn=ipadomain.net,cn=sysdb))] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_callback": 0x83ed260 (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_timeout": 0x83ed910 (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Running timer event 0x83ed260 "ltdb_callback" (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Destroying timer event 0x83ed910 "ltdb_timeout" (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Ending timer event 0x83ed260 "ltdb_callback" (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sysdb_get_direct_parents] (7): username is a member of 0 sysdb groups (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 1) (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_initgr_store_user_memberships] (8): Updating memberships for username (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): start ldb transaction (nesting: 2) (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): commit ldb transaction (nesting: 2) (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): commit ldb transaction (nesting: 1) (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): commit ldb transaction (nesting: 0) (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_get_initgr_done] (9): Initgroups done (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_id_op_connect_step] (9): reusing cached connection (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_id_op_destroy] (9): releasing operation connection (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_id_op_done] (9): releasing operation connection (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [acctinfo_callback] (4): Request processed. Returned 0,0,Success (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: sh[0x83cf0d0], connected[1], ops[(nil)], ldap[0x83d0370] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sbus_dispatch] (9): dbus conn: 83C4D10 (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sbus_dispatch] (9): Dispatching. (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sbus_message_handler] (9): Received SBUS method [pamHandler] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [be_pam_handler] (4): Got request with the following data (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [pam_print_data] (4): command: PAM_AUTHENTICATE (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [pam_print_data] (4): domain: ipadomain.net (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [pam_print_data] (4): user: username (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [pam_print_data] (4): service: sshd (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [pam_print_data] (4): tty: ssh (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [pam_print_data] (4): ruser: (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [pam_print_data] (4): rhost: 10.5.5.57 (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [pam_print_data] (4): authtok type: 1 (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [pam_print_data] (4): authtok size: 13 (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [pam_print_data] (4): newauthtok type: 0 (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [pam_print_data] (4): newauthtok size: 0 (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [pam_print_data] (4): priv: 0 (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [pam_print_data] (4): cli_pid: 11198 (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_callback": 0x83ccbb0 (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Added timed event "ltdb_timeout": 0x83cef20 (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Running timer event 0x83ccbb0 "ltdb_callback" (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Destroying timer event 0x83cef20 "ltdb_timeout" (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [ldb] (9): tevent: Ending timer event 0x83ccbb0 "ltdb_callback" (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [krb5_auth_send] (4): No ccache file for user [username] found. (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [krb5_auth_send] (9): Ccache_file is [not set] and is not active and TGT is not valid. (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [fo_resolve_service_send] (4): Trying to resolve service 'IPA' (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [get_server_status] (7): Status of server 'dc1.ipadomain.net' is 'name resolved' (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [get_port_status] (7): Port status of port 389 for server 'dc1.ipadomain.net' is 'working' (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [resolve_srv_send] (6): The status of SRV lookup is resolved (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [get_server_status] (7): Status of server 'dc1.ipadomain.net' is 'name resolved' (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [be_resolve_server_done] (7): Saving the first resolved server (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [be_resolve_server_done] (4): Found address for server dc1.ipadomain.net: [10.21.0.99] TTL 1200 (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [krb5_find_ccache_step] (9): Recreating ccache file. (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [child_handler_setup] (8): Setting up signal handler up for pid [11200] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [child_handler_setup] (8): Signal handler set up for pid [11200] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [write_pipe_handler] (6): All data has been sent! (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [read_pipe_handler] (6): EOF received, client finished (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [krb5_child_done] (9): child response [4][1][31]. (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [be_pam_handler_callback] (4): Backend returned: (0, 4, <NULL>) [Success] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [be_pam_handler_callback] (4): Sending result [4][ipadomain.net] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [be_pam_handler_callback] (4): Sent result [4][ipadomain.net] (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [child_sig_handler] (7): Waiting for child [11200]. (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [child_sig_handler] (4): child [11200] finished successfully. (Fri Sep 25 16:48:40 2015) [sssd[be[ipadomain.net]]] [sss_child_handler] (8): waitpid failed [10]: No child processes (Fri Sep 25 16:48:49 2015) [sssd[be[ipadomain.net]]] [sbus_dispatch] (9): dbus conn: 83B4700 (Fri Sep 25 16:48:49 2015) [sssd[be[ipadomain.net]]] [sbus_dispatch] (9): Dispatching. (Fri Sep 25 16:48:49 2015) [sssd[be[ipadomain.net]]] [sbus_message_handler] (9): Received SBUS method [ping] -- Manage your subscription for the Freeipa-users mailing list: https://www.redhat.com/mailman/listinfo/freeipa-users Go to http://freeipa.org for more info on the project