Things are still not working. Here is my olcDatabase={2}hdb.ldif file (which contains the access control):
dn: olcDatabase={2}hdb objectClass: olcDatabaseConfig objectClass: olcHdbConfig olcDatabase: {2}hdb olcDbDirectory: /var/lib/ldap olcSuffix: dc=deepsoft,dc=com olcRootDN: cn=Manager,dc=deepsoft,dc=com olcRootPW: {SSHA}rAk/xVPcZRGhumUTuc2T9xngcSQwL5Sx olcAccess: {0}to attrs=userPassword by self write by anonymous auth by dn=uid=sssd,ou=People,dc=deepsoft,dc=com read by dn=uid=nslcd,ou=People,dc=deepsoft,dc=com read by * none olcAccess: {1}to * by self write by anonymous auth by * read olcDbIndex: objectClass eq,pres olcDbIndex: ou,cn,mail,surname,givenname eq,pres,sub structuralObjectClass: olcHdbConfig entryUUID: 7e6a8cd4-30da-1037-9c55-458bcc6c0ce0 creatorsName: cn=config createTimestamp: 20170918163057Z entryCSN: 20170918163057.600191Z#000000#000#000000 modifiersName: cn=config modifyTimestamp: 20170918163057Z
And here is the log files from slapd (run with -s 128) and sssd_map (also with debugging enabled):
● slapd.service - OpenLDAP Server Daemon Loaded: loaded (/usr/lib/systemd/system/slapd.service; enabled; vendor preset: disabled) Active: active (running) since Thu 2017-09-21 09:46:06 EDT; 4min 7s ago Docs: man:slapd man:slapd-config man:slapd-hdb man:slapd-mdb file:///usr/share/doc/openldap-servers/guide.html Process: 17533 ExecStart=/usr/sbin/slapd -u ldap -h ${SLAPD_URLS} $SLAPD_OPTIONS (code=exited, status=0/SUCCESS) Process: 17495 ExecStartPre=/usr/libexec/openldap/check-config.sh (code=exited, status=0/SUCCESS) Main PID: 17535 (slapd) CGroup: /system.slice/slapd.service └─17535 /usr/sbin/slapd -u ldap -h ldapi:/// ldap://127.0.0.1/ ldap://192.168.250.98/ -s 128
Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: read access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: read access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: result not in cache (homeDirectory) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: read access to "uid=test2user,ou=People,dc=deepsoft,dc=com" "homeDirectory" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr homeDirectory Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "uid=test2user,ou=People,dc=deepsoft,dc=com", attr "homeDirectory" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: read access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: read access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: result not in cache (loginShell) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: read access to "uid=test2user,ou=People,dc=deepsoft,dc=com" "loginShell" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr loginShell Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "uid=test2user,ou=People,dc=deepsoft,dc=com", attr "loginShell" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: read access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: read access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: result not in cache (gecos) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: read access to "uid=test2user,ou=People,dc=deepsoft,dc=com" "gecos" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr gecos Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "uid=test2user,ou=People,dc=deepsoft,dc=com", attr "gecos" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: read access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: read access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: result not in cache (shadowLastChange) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: read access to "uid=test2user,ou=People,dc=deepsoft,dc=com" "shadowLastChange" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr shadowLastChange Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "uid=test2user,ou=People,dc=deepsoft,dc=com", attr "shadowLastChange" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: read access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: read access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: result not in cache (shadowMax) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: read access to "uid=test2user,ou=People,dc=deepsoft,dc=com" "shadowMax" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr shadowMax Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "uid=test2user,ou=People,dc=deepsoft,dc=com", attr "shadowMax" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: read access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: read access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: result not in cache (userPassword) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: read access to "uid=test2user,ou=People,dc=deepsoft,dc=com" "userPassword" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [1] attr userPassword Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "uid=test2user,ou=People,dc=deepsoft,dc=com", attr "userPassword" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: uid=sssd,ou=people,dc=deepsoft,dc=com Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: read access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: read access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: result not in cache (modifyTimestamp) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: read access to "uid=test2user,ou=People,dc=deepsoft,dc=com" "modifyTimestamp" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr modifyTimestamp Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "uid=test2user,ou=People,dc=deepsoft,dc=com", attr "modifyTimestamp" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: read access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: read access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "uid=test3user,ou=People,dc=deepsoft,dc=com" "uid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr uid Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "uid=test3user,ou=People,dc=deepsoft,dc=com", attr "uid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "uid=heller,ou=People,dc=deepsoft,dc=com" "uid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr uid Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "uid=heller,ou=People,dc=deepsoft,dc=com", attr "uid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "uid=nslcd,ou=People,dc=deepsoft,dc=com" "uid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr uid Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "uid=nslcd,ou=People,dc=deepsoft,dc=com", attr "uid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "uid=sssd,ou=People,dc=deepsoft,dc=com" "uid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr uid Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "uid=sssd,ou=People,dc=deepsoft,dc=com", attr "uid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [1] applying write(=wrscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [1] mask: write(=wrscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by write(=wrscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by write(=wrscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: conn=1000 op=9 SEARCH RESULT tag=101 err=0 nentries=1 text= Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: conn=1000 op=10 SRCH base="dc=deepsoft,dc=com" scope=2 deref=0 filter="(&(memberUid=test2user)(objectClass=posixGroup)(cn=*)(&(gidNumber=*)(!(gidNumber=0))))" Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: conn=1000 op=10 SRCH attr=objectClass cn userPassword gidNumber modifyTimestamp modifyTimestamp Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "dc=deepsoft,dc=com" "entry" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr entry Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "dc=deepsoft,dc=com", attr "entry" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to all values by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= bdb_equality_candidates: (memberUid) not indexed Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "cn=testuser,ou=Group,dc=deepsoft,dc=com" "memberUid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr memberUid Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "cn=testuser,ou=Group,dc=deepsoft,dc=com", attr "memberUid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "cn=Domain Admins,ou=Groups,dc=deepsoft,dc=com" "memberUid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr memberUid Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "cn=Domain Admins,ou=Groups,dc=deepsoft,dc=com", attr "memberUid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "cn=Domain Users,ou=Groups,dc=deepsoft,dc=com" "memberUid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr memberUid Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "cn=Domain Users,ou=Groups,dc=deepsoft,dc=com", attr "memberUid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "cn=Domain Users,ou=Groups,dc=deepsoft,dc=com" "objectClass" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr objectClass Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "cn=Domain Users,ou=Groups,dc=deepsoft,dc=com", attr "objectClass" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "cn=Domain Users,ou=Groups,dc=deepsoft,dc=com" "cn" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr cn Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "cn=Domain Users,ou=Groups,dc=deepsoft,dc=com", attr "cn" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to all values by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "cn=Domain Users,ou=Groups,dc=deepsoft,dc=com" "gidNumber" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr gidNumber Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "cn=Domain Users,ou=Groups,dc=deepsoft,dc=com", attr "gidNumber" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to all values by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "cn=Domain Users,ou=Groups,dc=deepsoft,dc=com" "gidNumber" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr gidNumber Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "cn=Domain Users,ou=Groups,dc=deepsoft,dc=com", attr "gidNumber" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: read access to "cn=Domain Users,ou=Groups,dc=deepsoft,dc=com" "entry" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr entry Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "cn=Domain Users,ou=Groups,dc=deepsoft,dc=com", attr "entry" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to all values by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: read access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: read access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: result not in cache (objectClass) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: read access to "cn=Domain Users,ou=Groups,dc=deepsoft,dc=com" "objectClass" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr objectClass Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "cn=Domain Users,ou=Groups,dc=deepsoft,dc=com", attr "objectClass" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: read access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: read access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: result was in cache (objectClass) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: result was in cache (objectClass) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: result not in cache (cn) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: read access to "cn=Domain Users,ou=Groups,dc=deepsoft,dc=com" "cn" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr cn Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "cn=Domain Users,ou=Groups,dc=deepsoft,dc=com", attr "cn" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: read access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: read access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: result not in cache (gidNumber) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: read access to "cn=Domain Users,ou=Groups,dc=deepsoft,dc=com" "gidNumber" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr gidNumber Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "cn=Domain Users,ou=Groups,dc=deepsoft,dc=com", attr "gidNumber" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: read access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: read access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: result not in cache (modifyTimestamp) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: read access to "cn=Domain Users,ou=Groups,dc=deepsoft,dc=com" "modifyTimestamp" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr modifyTimestamp Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "cn=Domain Users,ou=Groups,dc=deepsoft,dc=com", attr "modifyTimestamp" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: read access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: read access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "cn=Domain Guests,ou=Groups,dc=deepsoft,dc=com" "memberUid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr memberUid Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "cn=Domain Guests,ou=Groups,dc=deepsoft,dc=com", attr "memberUid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "cn=Domain Computers,ou=Groups,dc=deepsoft,dc=com" "memberUid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr memberUid Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "cn=Domain Computers,ou=Groups,dc=deepsoft,dc=com", attr "memberUid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "cn=Administrators,ou=Groups,dc=deepsoft,dc=com" "memberUid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr memberUid Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "cn=Administrators,ou=Groups,dc=deepsoft,dc=com", attr "memberUid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "cn=Account Operators,ou=Groups,dc=deepsoft,dc=com" "memberUid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr memberUid Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "cn=Account Operators,ou=Groups,dc=deepsoft,dc=com", attr "memberUid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "cn=Print Operators,ou=Groups,dc=deepsoft,dc=com" "memberUid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr memberUid Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "cn=Print Operators,ou=Groups,dc=deepsoft,dc=com", attr "memberUid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "cn=Backup Operators,ou=Groups,dc=deepsoft,dc=com" "memberUid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr memberUid Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "cn=Backup Operators,ou=Groups,dc=deepsoft,dc=com", attr "memberUid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "cn=Replicators,ou=Groups,dc=deepsoft,dc=com" "memberUid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr memberUid Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "cn=Replicators,ou=Groups,dc=deepsoft,dc=com", attr "memberUid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "cn=heller,ou=Group,dc=deepsoft,dc=com" "memberUid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr memberUid Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "cn=heller,ou=Group,dc=deepsoft,dc=com", attr "memberUid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "cn=nslcd,ou=Group,dc=deepsoft,dc=com" "memberUid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr memberUid Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "cn=nslcd,ou=Group,dc=deepsoft,dc=com", attr "memberUid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "cn=sssd,ou=Group,dc=deepsoft,dc=com" "memberUid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr memberUid Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "cn=sssd,ou=Group,dc=deepsoft,dc=com", attr "memberUid" requested Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: conn=1000 op=10 SEARCH RESULT tag=101 err=0 nentries=1 text= Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: conn=1004 fd=19 ACCEPT from IP=192.168.250.98:32894 (IP=192.168.250.98:389) Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: conn=1004 op=0 EXT oid=1.3.6.1.4.1.1466.20037 Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: conn=1004 op=0 STARTTLS Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: conn=1004 op=0 RESULT oid= err=0 text= Sep 21 09:47:09 c764guest.deepsoft.com slapd[17535]: conn=1004 fd=19 closed (TLS negotiation failure) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: conn=1000 op=11 SRCH base="dc=deepsoft,dc=com" scope=2 deref=0 filter="(&(uid=gdm)(objectClass=posixAccount)(&(uidNumber=*)(!(uidNumber=0))))" Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: conn=1000 op=11 SRCH attr=objectClass uid userPassword uidNumber gidNumber gecos homeDirectory loginShell krbPrincipalName cn modifyTimestamp modifyTimestamp shadowLastChange shadowMin shadowMax shadowWarning shadowInactive shadowExpire shadowFlag krbLastPwdChange krbPasswordExpiration pwdAttribute authorizedService accountExpires userAccountControl nsAccountLock host loginDisabled loginExpirationTime loginAllowedTimeMap sshPublicKey mail Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "dc=deepsoft,dc=com" "entry" requested Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr entry Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "dc=deepsoft,dc=com", attr "entry" requested Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_mask: to all values by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= bdb_equality_candidates: (uid) not indexed Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "uid=testuser,ou=People,dc=deepsoft,dc=com" "uid" requested Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr uid Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "uid=testuser,ou=People,dc=deepsoft,dc=com", attr "uid" requested Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "uid=root,ou=People,dc=deepsoft,dc=com" "uid" requested Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr uid Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "uid=root,ou=People,dc=deepsoft,dc=com", attr "uid" requested Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "uid=nobody,ou=People,dc=deepsoft,dc=com" "uid" requested Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr uid Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "uid=nobody,ou=People,dc=deepsoft,dc=com", attr "uid" requested Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "uid=test2user,ou=People,dc=deepsoft,dc=com" "uid" requested Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr uid Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "uid=test2user,ou=People,dc=deepsoft,dc=com", attr "uid" requested Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "uid=test3user,ou=People,dc=deepsoft,dc=com" "uid" requested Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr uid Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "uid=test3user,ou=People,dc=deepsoft,dc=com", attr "uid" requested Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "uid=heller,ou=People,dc=deepsoft,dc=com" "uid" requested Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr uid Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "uid=heller,ou=People,dc=deepsoft,dc=com", attr "uid" requested Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "uid=nslcd,ou=People,dc=deepsoft,dc=com" "uid" requested Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr uid Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "uid=nslcd,ou=People,dc=deepsoft,dc=com", attr "uid" requested Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: anonymous Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: * Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] applying read(=rscxd) (stop) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [3] mask: read(=rscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by read(=rscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by read(=rscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access to "uid=sssd,ou=People,dc=deepsoft,dc=com" "uid" requested Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_get: [2] attr uid Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_mask: access to entry "uid=sssd,ou=People,dc=deepsoft,dc=com", attr "uid" requested Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => acl_mask: to value by "uid=sssd,ou=people,dc=deepsoft,dc=com", (=0) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= check a_dn_pat: self Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [1] applying write(=wrscxd) (stop) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: <= acl_mask: [1] mask: write(=wrscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => slap_access_allowed: search access granted by write(=wrscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: => access_allowed: search access granted by write(=wrscxd) Sep 21 09:50:01 c764guest.deepsoft.com slapd[17535]: conn=1000 op=11 SEARCH RESULT tag=101 err=0 nentries=0 text=
(Thu Sep 21 09:18:53 2017) [sssd[pam]] [get_client_cred] (0x4000): Client creds: euid[0] egid[0] pid[16951]. (Thu Sep 21 09:18:53 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f9200be9040][23] (Thu Sep 21 09:18:53 2017) [sssd[pam]] [accept_fd_handler] (0x0400): Client connected to privileged pipe! (Thu Sep 21 09:18:53 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f9200be9040][23] (Thu Sep 21 09:18:53 2017) [sssd[pam]] [sss_cmd_get_version] (0x0200): Received client version [3]. (Thu Sep 21 09:18:53 2017) [sssd[pam]] [sss_cmd_get_version] (0x0200): Offered version [3]. (Thu Sep 21 09:18:53 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f9200be9040][23] (Thu Sep 21 09:18:53 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f9200be9040][23] (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_cmd_open_session] (0x0100): entering pam_cmd_open_session (Thu Sep 21 09:18:53 2017) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'heller' matched without domain, user is heller (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_print_data] (0x0100): command: SSS_PAM_OPEN_SESSION (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_print_data] (0x0100): domain: not set (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_print_data] (0x0100): user: heller (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_print_data] (0x0100): service: sshd (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost: sauron.deepsoft.com (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0 (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0 (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 1 (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 16951 (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_print_data] (0x0100): logon name: heller (Thu Sep 21 09:18:53 2017) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/default/heller@default] (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_initgr_check_timeout] (0x4000): User [heller] not found in PAM cache. (Thu Sep 21 09:18:53 2017) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x7f91feb07030:3:heller@default@default] (Thu Sep 21 09:18:53 2017) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [default][0x3][BE_REQ_INITGROUPS][1][name=heller@default:-] (Thu Sep 21 09:18:53 2017) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x7f9200bed8b0 (Thu Sep 21 09:18:53 2017) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x7f91feb07030:3:heller@default@default] (Thu Sep 21 09:18:53 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x7f9200bed8b0 (Thu Sep 21 09:18:53 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x7f9200bdf5b0 (Thu Sep 21 09:18:53 2017) [sssd[pam]] [sbus_dispatch] (0x4000): Dispatching. (Thu Sep 21 09:18:53 2017) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 1 errno: 11 error message: Init group lookup failed (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_check_user_dp_callback] (0x0040): Unable to get information from Data Provider (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [heller@default] (Thu Sep 21 09:18:53 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7f9200bf1bf0 (Thu Sep 21 09:18:53 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7f9200bf1cb0 (Thu Sep 21 09:18:53 2017) [sssd[pam]] [ldb] (0x4000): Running timer event 0x7f9200bf1bf0 "ltdb_callback" (Thu Sep 21 09:18:53 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer event 0x7f9200bf1cb0 "ltdb_timeout" (Thu Sep 21 09:18:53 2017) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x7f9200bf1bf0 "ltdb_callback" (Thu Sep 21 09:18:53 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7f9200bedfa0 (Thu Sep 21 09:18:53 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7f9200bf1670 (Thu Sep 21 09:18:53 2017) [sssd[pam]] [ldb] (0x4000): Running timer event 0x7f9200bedfa0 "ltdb_callback" (Thu Sep 21 09:18:53 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer event 0x7f9200bf1670 "ltdb_timeout" (Thu Sep 21 09:18:53 2017) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x7f9200bedfa0 "ltdb_callback" (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [heller@default@default] (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pd_set_primary_name] (0x0400): User's primary name is heller@default (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [heller] added to PAM initgroup cache (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data: (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_print_data] (0x0100): command: SSS_PAM_OPEN_SESSION (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_print_data] (0x0100): domain: default (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_print_data] (0x0100): user: heller@default (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_print_data] (0x0100): service: sshd (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost: sauron.deepsoft.com (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0 (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0 (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 1 (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 16951 (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_print_data] (0x0100): logon name: heller (Thu Sep 21 09:18:53 2017) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x7f9200bed2b0 (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0 (Thu Sep 21 09:18:53 2017) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x7f91feb07030:3:heller@default@default] (Thu Sep 21 09:18:53 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x7f9200bed2b0 (Thu Sep 21 09:18:53 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x7f9200bdf5b0 (Thu Sep 21 09:18:53 2017) [sssd[pam]] [sbus_dispatch] (0x4000): Dispatching. (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_dp_process_reply] (0x0200): received: [0 (Success)][default] (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0]: Success. (Thu Sep 21 09:18:53 2017) [sssd[pam]] [pam_reply] (0x0200): blen: 24 (Thu Sep 21 09:18:53 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f9200be9040][23] (Thu Sep 21 09:18:58 2017) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [heller] removed from PAM initgroup cache (Thu Sep 21 09:19:01 2017) [sssd[pam]] [get_client_cred] (0x4000): Client creds: euid[0] egid[0] pid[16994]. (Thu Sep 21 09:19:01 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f9200bed350][24] (Thu Sep 21 09:19:01 2017) [sssd[pam]] [accept_fd_handler] (0x0400): Client connected to privileged pipe! (Thu Sep 21 09:19:01 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f9200bed350][24] (Thu Sep 21 09:19:01 2017) [sssd[pam]] [sss_cmd_get_version] (0x0200): Received client version [3]. (Thu Sep 21 09:19:01 2017) [sssd[pam]] [sss_cmd_get_version] (0x0200): Offered version [3]. (Thu Sep 21 09:19:01 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f9200bed350][24] (Thu Sep 21 09:19:01 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f9200bed350][24] (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_cmd_open_session] (0x0100): entering pam_cmd_open_session (Thu Sep 21 09:19:01 2017) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'heller' matched without domain, user is heller (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_print_data] (0x0100): command: SSS_PAM_OPEN_SESSION (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_print_data] (0x0100): domain: not set (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_print_data] (0x0100): user: heller (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_print_data] (0x0100): service: sshd (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost: sauron.deepsoft.com (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0 (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0 (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 1 (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 16994 (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_print_data] (0x0100): logon name: heller (Thu Sep 21 09:19:01 2017) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/default/heller@default] (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_initgr_check_timeout] (0x4000): User [heller] not found in PAM cache. (Thu Sep 21 09:19:01 2017) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x7f91feb07030:3:heller@default@default] (Thu Sep 21 09:19:01 2017) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [default][0x3][BE_REQ_INITGROUPS][1][name=heller@default:-] (Thu Sep 21 09:19:01 2017) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x7f9200bed2b0 (Thu Sep 21 09:19:01 2017) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x7f91feb07030:3:heller@default@default] (Thu Sep 21 09:19:01 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x7f9200bed2b0 (Thu Sep 21 09:19:01 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x7f9200bdf5b0 (Thu Sep 21 09:19:01 2017) [sssd[pam]] [sbus_dispatch] (0x4000): Dispatching. (Thu Sep 21 09:19:01 2017) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 1 errno: 11 error message: Init group lookup failed (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_check_user_dp_callback] (0x0040): Unable to get information from Data Provider (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [heller@default] (Thu Sep 21 09:19:01 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7f9200bf21c0 (Thu Sep 21 09:19:01 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7f9200bf2280 (Thu Sep 21 09:19:01 2017) [sssd[pam]] [ldb] (0x4000): Running timer event 0x7f9200bf21c0 "ltdb_callback" (Thu Sep 21 09:19:01 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer event 0x7f9200bf2280 "ltdb_timeout" (Thu Sep 21 09:19:01 2017) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x7f9200bf21c0 "ltdb_callback" (Thu Sep 21 09:19:01 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7f9200bf0730 (Thu Sep 21 09:19:01 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7f9200bf3b00 (Thu Sep 21 09:19:01 2017) [sssd[pam]] [ldb] (0x4000): Running timer event 0x7f9200bf0730 "ltdb_callback" (Thu Sep 21 09:19:01 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer event 0x7f9200bf3b00 "ltdb_timeout" (Thu Sep 21 09:19:01 2017) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x7f9200bf0730 "ltdb_callback" (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [heller@default@default] (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pd_set_primary_name] (0x0400): User's primary name is heller@default (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [heller] added to PAM initgroup cache (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data: (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_print_data] (0x0100): command: SSS_PAM_OPEN_SESSION (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_print_data] (0x0100): domain: default (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_print_data] (0x0100): user: heller@default (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_print_data] (0x0100): service: sshd (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost: sauron.deepsoft.com (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0 (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0 (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 1 (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 16994 (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_print_data] (0x0100): logon name: heller (Thu Sep 21 09:19:01 2017) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x7f9200bf1ca0 (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0 (Thu Sep 21 09:19:01 2017) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x7f91feb07030:3:heller@default@default] (Thu Sep 21 09:19:01 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x7f9200bf1ca0 (Thu Sep 21 09:19:01 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x7f9200bdf5b0 (Thu Sep 21 09:19:01 2017) [sssd[pam]] [sbus_dispatch] (0x4000): Dispatching. (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_dp_process_reply] (0x0200): received: [0 (Success)][default] (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0]: Success. (Thu Sep 21 09:19:01 2017) [sssd[pam]] [pam_reply] (0x0200): blen: 24 (Thu Sep 21 09:19:01 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f9200bed350][24] (Thu Sep 21 09:19:06 2017) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [heller] removed from PAM initgroup cache (Thu Sep 21 09:19:53 2017) [sssd[pam]] [idle_handler] (0x2000): Terminating idle client [0x7f9200be9040][23] (Thu Sep 21 09:19:53 2017) [sssd[pam]] [client_close_fn] (0x2000): Terminated client [0x7f9200be9040][23] (Thu Sep 21 09:20:01 2017) [sssd[pam]] [idle_handler] (0x2000): Terminating idle client [0x7f9200bed350][24] (Thu Sep 21 09:20:01 2017) [sssd[pam]] [client_close_fn] (0x2000): Terminated client [0x7f9200bed350][24] (Thu Sep 21 09:25:01 2017) [sssd[pam]] [get_client_cred] (0x4000): Client creds: euid[0] egid[0] pid[17137]. (Thu Sep 21 09:25:01 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f9200bf1bd0][23] (Thu Sep 21 09:25:01 2017) [sssd[pam]] [accept_fd_handler] (0x0400): Client connected to privileged pipe! (Thu Sep 21 09:25:01 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f9200bf1bd0][23] (Thu Sep 21 09:25:01 2017) [sssd[pam]] [sss_cmd_get_version] (0x0200): Received client version [3]. (Thu Sep 21 09:25:01 2017) [sssd[pam]] [sss_cmd_get_version] (0x0200): Offered version [3]. (Thu Sep 21 09:25:01 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f9200bf1bd0][23] (Thu Sep 21 09:25:01 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f9200bf1bd0][23] (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_cmd_open_session] (0x0100): entering pam_cmd_open_session (Thu Sep 21 09:25:01 2017) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'pcp' matched without domain, user is pcp (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_print_data] (0x0100): command: SSS_PAM_OPEN_SESSION (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_print_data] (0x0100): domain: not set (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_print_data] (0x0100): user: pcp (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_print_data] (0x0100): service: crond (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: cron (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0 (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0 (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 1 (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 17137 (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_print_data] (0x0100): logon name: pcp (Thu Sep 21 09:25:01 2017) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/default/pcp@default] (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_initgr_check_timeout] (0x4000): User [pcp] not found in PAM cache. (Thu Sep 21 09:25:01 2017) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x7f91feb07030:3:pcp@default@default] (Thu Sep 21 09:25:01 2017) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [default][0x3][BE_REQ_INITGROUPS][1][name=pcp@default:-] (Thu Sep 21 09:25:01 2017) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x7f9200bf1990 (Thu Sep 21 09:25:01 2017) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x7f91feb07030:3:pcp@default@default] (Thu Sep 21 09:25:01 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x7f9200bf1990 (Thu Sep 21 09:25:01 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x7f9200bdf5b0 (Thu Sep 21 09:25:01 2017) [sssd[pam]] [sbus_dispatch] (0x4000): Dispatching. (Thu Sep 21 09:25:01 2017) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 1 errno: 11 error message: Init group lookup failed (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_check_user_dp_callback] (0x0040): Unable to get information from Data Provider (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [pcp@default] (Thu Sep 21 09:25:01 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7f9200bf1e30 (Thu Sep 21 09:25:01 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7f9200bf1ef0 (Thu Sep 21 09:25:01 2017) [sssd[pam]] [ldb] (0x4000): Running timer event 0x7f9200bf1e30 "ltdb_callback" (Thu Sep 21 09:25:01 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer event 0x7f9200bf1ef0 "ltdb_timeout" (Thu Sep 21 09:25:01 2017) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x7f9200bf1e30 "ltdb_callback" (Thu Sep 21 09:25:01 2017) [sssd[pam]] [sss_ncache_set_str] (0x0400): Adding [NCE/USER/default/pcp] to negative cache (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [10]: User not known to the underlying authentication module. (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_reply] (0x0200): blen: 8 (Thu Sep 21 09:25:01 2017) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x7f91feb07030:3:pcp@default@default] (Thu Sep 21 09:25:01 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f9200bf1bd0][23] (Thu Sep 21 09:25:01 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f9200bf1bd0][23] (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_cmd_close_session] (0x0100): entering pam_cmd_close_session (Thu Sep 21 09:25:01 2017) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'pcp' matched without domain, user is pcp (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_print_data] (0x0100): command: SSS_PAM_CLOSE_SESSION (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_print_data] (0x0100): domain: not set (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_print_data] (0x0100): user: pcp (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_print_data] (0x0100): service: crond (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: cron (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0 (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0 (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 1 (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 17137 (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_print_data] (0x0100): logon name: pcp (Thu Sep 21 09:25:01 2017) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/default/pcp@default] (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_initgr_check_timeout] (0x4000): User [pcp] not found in PAM cache. (Thu Sep 21 09:25:01 2017) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x7f91feb07030:3:pcp@default@default] (Thu Sep 21 09:25:01 2017) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [default][0x3][BE_REQ_INITGROUPS][1][name=pcp@default:-] (Thu Sep 21 09:25:01 2017) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x7f9200bf1990 (Thu Sep 21 09:25:01 2017) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x7f91feb07030:3:pcp@default@default] (Thu Sep 21 09:25:01 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x7f9200bf1990 (Thu Sep 21 09:25:01 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x7f9200bdf5b0 (Thu Sep 21 09:25:01 2017) [sssd[pam]] [sbus_dispatch] (0x4000): Dispatching. (Thu Sep 21 09:25:01 2017) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 1 errno: 11 error message: Init group lookup failed (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_check_user_dp_callback] (0x0040): Unable to get information from Data Provider (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [pcp@default] (Thu Sep 21 09:25:01 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7f9200bf1e30 (Thu Sep 21 09:25:01 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7f9200bf1ef0 (Thu Sep 21 09:25:01 2017) [sssd[pam]] [ldb] (0x4000): Running timer event 0x7f9200bf1e30 "ltdb_callback" (Thu Sep 21 09:25:01 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer event 0x7f9200bf1ef0 "ltdb_timeout" (Thu Sep 21 09:25:01 2017) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x7f9200bf1e30 "ltdb_callback" (Thu Sep 21 09:25:01 2017) [sssd[pam]] [sss_ncache_set_str] (0x0400): Adding [NCE/USER/default/pcp] to negative cache (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [10]: User not known to the underlying authentication module. (Thu Sep 21 09:25:01 2017) [sssd[pam]] [pam_reply] (0x0200): blen: 8 (Thu Sep 21 09:25:01 2017) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x7f91feb07030:3:pcp@default@default] (Thu Sep 21 09:25:01 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f9200bf1bd0][23] (Thu Sep 21 09:25:01 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f9200bf1bd0][23] (Thu Sep 21 09:25:01 2017) [sssd[pam]] [client_recv] (0x0200): Client disconnected! (Thu Sep 21 09:25:01 2017) [sssd[pam]] [client_close_fn] (0x2000): Terminated client [0x7f9200bf1bd0][23] (Thu Sep 21 09:28:01 2017) [sssd[pam]] [get_client_cred] (0x4000): Client creds: euid[0] egid[0] pid[17208]. (Thu Sep 21 09:28:01 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f9200bf03e0][23] (Thu Sep 21 09:28:01 2017) [sssd[pam]] [accept_fd_handler] (0x0400): Client connected to privileged pipe! (Thu Sep 21 09:28:01 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f9200bf03e0][23] (Thu Sep 21 09:28:01 2017) [sssd[pam]] [sss_cmd_get_version] (0x0200): Received client version [3]. (Thu Sep 21 09:28:01 2017) [sssd[pam]] [sss_cmd_get_version] (0x0200): Offered version [3]. (Thu Sep 21 09:28:01 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f9200bf03e0][23] (Thu Sep 21 09:28:01 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f9200bf03e0][23] (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_cmd_open_session] (0x0100): entering pam_cmd_open_session (Thu Sep 21 09:28:01 2017) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'pcp' matched without domain, user is pcp (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_print_data] (0x0100): command: SSS_PAM_OPEN_SESSION (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_print_data] (0x0100): domain: not set (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_print_data] (0x0100): user: pcp (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_print_data] (0x0100): service: crond (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: cron (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0 (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0 (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 1 (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 17208 (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_print_data] (0x0100): logon name: pcp (Thu Sep 21 09:28:01 2017) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/default/pcp@default] (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_initgr_check_timeout] (0x4000): User [pcp] not found in PAM cache. (Thu Sep 21 09:28:01 2017) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x7f91feb07030:3:pcp@default@default] (Thu Sep 21 09:28:01 2017) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [default][0x3][BE_REQ_INITGROUPS][1][name=pcp@default:-] (Thu Sep 21 09:28:01 2017) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x7f9200bf04b0 (Thu Sep 21 09:28:01 2017) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x7f91feb07030:3:pcp@default@default] (Thu Sep 21 09:28:01 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x7f9200bf04b0 (Thu Sep 21 09:28:01 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x7f9200bdf5b0 (Thu Sep 21 09:28:01 2017) [sssd[pam]] [sbus_dispatch] (0x4000): Dispatching. (Thu Sep 21 09:28:01 2017) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 1 errno: 11 error message: Init group lookup failed (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_check_user_dp_callback] (0x0040): Unable to get information from Data Provider (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [pcp@default] (Thu Sep 21 09:28:01 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7f9200bf1e80 (Thu Sep 21 09:28:01 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7f9200bf1f40 (Thu Sep 21 09:28:01 2017) [sssd[pam]] [ldb] (0x4000): Running timer event 0x7f9200bf1e80 "ltdb_callback" (Thu Sep 21 09:28:01 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer event 0x7f9200bf1f40 "ltdb_timeout" (Thu Sep 21 09:28:01 2017) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x7f9200bf1e80 "ltdb_callback" (Thu Sep 21 09:28:01 2017) [sssd[pam]] [sss_ncache_set_str] (0x0400): Adding [NCE/USER/default/pcp] to negative cache (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [10]: User not known to the underlying authentication module. (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_reply] (0x0200): blen: 8 (Thu Sep 21 09:28:01 2017) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x7f91feb07030:3:pcp@default@default] (Thu Sep 21 09:28:01 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f9200bf03e0][23] (Thu Sep 21 09:28:01 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f9200bf03e0][23] (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_cmd_close_session] (0x0100): entering pam_cmd_close_session (Thu Sep 21 09:28:01 2017) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'pcp' matched without domain, user is pcp (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_print_data] (0x0100): command: SSS_PAM_CLOSE_SESSION (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_print_data] (0x0100): domain: not set (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_print_data] (0x0100): user: pcp (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_print_data] (0x0100): service: crond (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: cron (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0 (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0 (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 1 (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 17208 (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_print_data] (0x0100): logon name: pcp (Thu Sep 21 09:28:01 2017) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/default/pcp@default] (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_initgr_check_timeout] (0x4000): User [pcp] not found in PAM cache. (Thu Sep 21 09:28:01 2017) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x7f91feb07030:3:pcp@default@default] (Thu Sep 21 09:28:01 2017) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [default][0x3][BE_REQ_INITGROUPS][1][name=pcp@default:-] (Thu Sep 21 09:28:01 2017) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x7f9200bf04b0 (Thu Sep 21 09:28:01 2017) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x7f91feb07030:3:pcp@default@default] (Thu Sep 21 09:28:01 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x7f9200bf04b0 (Thu Sep 21 09:28:01 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x7f9200bdf5b0 (Thu Sep 21 09:28:01 2017) [sssd[pam]] [sbus_dispatch] (0x4000): Dispatching. (Thu Sep 21 09:28:01 2017) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 1 errno: 11 error message: Init group lookup failed (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_check_user_dp_callback] (0x0040): Unable to get information from Data Provider (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [pcp@default] (Thu Sep 21 09:28:01 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7f9200bf1e80 (Thu Sep 21 09:28:01 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7f9200bf1f40 (Thu Sep 21 09:28:01 2017) [sssd[pam]] [ldb] (0x4000): Running timer event 0x7f9200bf1e80 "ltdb_callback" (Thu Sep 21 09:28:01 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer event 0x7f9200bf1f40 "ltdb_timeout" (Thu Sep 21 09:28:01 2017) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x7f9200bf1e80 "ltdb_callback" (Thu Sep 21 09:28:01 2017) [sssd[pam]] [sss_ncache_set_str] (0x0400): Adding [NCE/USER/default/pcp] to negative cache (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [10]: User not known to the underlying authentication module. (Thu Sep 21 09:28:01 2017) [sssd[pam]] [pam_reply] (0x0200): blen: 8 (Thu Sep 21 09:28:01 2017) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x7f91feb07030:3:pcp@default@default] (Thu Sep 21 09:28:01 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f9200bf03e0][23] (Thu Sep 21 09:28:01 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f9200bf03e0][23] (Thu Sep 21 09:28:01 2017) [sssd[pam]] [client_recv] (0x0200): Client disconnected! (Thu Sep 21 09:28:01 2017) [sssd[pam]] [client_close_fn] (0x2000): Terminated client [0x7f9200bf03e0][23] (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_remove_watch] (0x2000): 0x7f9200be0310/0x7f9200bdf130 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_remove_watch] (0x2000): 0x7f9200be0310/0x7f9200bdf0e0 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [orderly_shutdown] (0x0010): SIGTERM: killing children (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sss_responder_ctx_destructor] (0x0400): Responder is being shut down (Thu Sep 21 09:46:10 2017) [sssd[pam]] [server_setup] (0x0400): CONFDB: /var/lib/sss/db/config.ldb (Thu Sep 21 09:46:10 2017) [sssd[pam]] [confdb_get_domain_internal] (0x0400): No enumeration for [default]! (Thu Sep 21 09:46:10 2017) [sssd[pam]] [confdb_get_domain_internal] (0x1000): pwd_expiration_warning is -1 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_init_connection] (0x0400): Adding connection 0x7ff3c2188be0 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_add_watch] (0x2000): 0x7ff3c218b0c0/0x7ff3c2189c40 (17), -/W (enabled) (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000): 0x7ff3c218b0c0/0x7ff3c2188050 (17), R/- (disabled) (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_opath_hash_add_iface] (0x0400): Registering interface org.freedesktop.sssd.service with path /org/freedesktop/sssd/service (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_conn_register_path] (0x0400): Registering object path /org/freedesktop/sssd/service with D-Bus connection (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_opath_hash_add_iface] (0x0400): Registering interface org.freedesktop.DBus.Properties with path /org/freedesktop/sssd/service (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_opath_hash_add_iface] (0x0400): Registering interface org.freedesktop.DBus.Introspectable with path /org/freedesktop/sssd/service (Thu Sep 21 09:46:10 2017) [sssd[pam]] [monitor_common_send_id] (0x0100): Sending ID: (pam,1) (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x7ff3c218b760 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000): 0x7ff3c218b0c0/0x7ff3c2188050 (17), R/- (enabled) (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000): 0x7ff3c218b0c0/0x7ff3c2189c40 (17), -/W (disabled) (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sss_names_init_from_args] (0x0100): Using re [(?P<name>[^@]+)@?(?P<domain>[^@]*$)]. (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sss_fqnames_init] (0x0100): Using fq format [%1$s@%2$s]. (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_init_connection] (0x0400): Adding connection 0x7ff3c218c960 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_add_watch] (0x2000): 0x7ff3c218d6c0/0x7ff3c218c490 (18), -/W (enabled) (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000): 0x7ff3c218d6c0/0x7ff3c218c4e0 (18), R/- (disabled) (Thu Sep 21 09:46:10 2017) [sssd[pam]] [rdp_message_send_internal] (0x0400): DP Request: /org/freedesktop/sssd/dataprovider org.freedesktop.sssd.DataProvider.Client.Register (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x7ff3c218de70 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000): 0x7ff3c218d6c0/0x7ff3c218c4e0 (18), R/- (enabled) (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000): 0x7ff3c218d6c0/0x7ff3c218c490 (18), -/W (disabled) (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sysdb_domain_init_internal] (0x0200): DB File for default: /var/lib/sss/db/cache_default.ldb (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sysdb_domain_init_internal] (0x0200): Timestamp file for default: /var/lib/sss/db/timestamps_default.ldb (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7ff3c21906d0 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7ff3c2190790 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Running timer event 0x7ff3c21906d0 "ltdb_callback" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer event 0x7ff3c2190790 "ltdb_timeout" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x7ff3c21906d0 "ltdb_callback" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x0400): asq: Unable to register control with rootdse! (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7ff3c2190870 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7ff3c2190930 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Running timer event 0x7ff3c2190870 "ltdb_callback" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer event 0x7ff3c2190930 "ltdb_timeout" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x7ff3c2190870 "ltdb_callback" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7ff3c2190ad0 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7ff3c2190b90 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Running timer event 0x7ff3c2190ad0 "ltdb_callback" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer event 0x7ff3c2190b90 "ltdb_timeout" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x7ff3c2190ad0 "ltdb_callback" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7ff3c2192050 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7ff3c2192110 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Running timer event 0x7ff3c2192050 "ltdb_callback" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer event 0x7ff3c2192110 "ltdb_timeout" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x7ff3c2192050 "ltdb_callback" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): no modules required by the db (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): No modules specified for this database (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7ff3c2192110 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7ff3c21921d0 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Running timer event 0x7ff3c2192110 "ltdb_callback" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer event 0x7ff3c21921d0 "ltdb_timeout" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x7ff3c2192110 "ltdb_callback" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7ff3c21922b0 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7ff3c2192370 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Running timer event 0x7ff3c21922b0 "ltdb_callback" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer event 0x7ff3c2192370 "ltdb_timeout" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x7ff3c21922b0 "ltdb_callback" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sss_process_init] (0x0400): Responder Initialization complete (Thu Sep 21 09:46:10 2017) [sssd[pam]] [get_trusted_uids] (0x0400): All UIDs are allowed. (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'root' matched without domain, user is root (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sss_ncache_set_str] (0x0400): Adding [NCE/USER/default/root@default] to negative cache permanently (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'root' matched without domain, user is root (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sss_ncache_set_str] (0x0400): Adding [NCE/GROUP/default/root@default] to negative cache permanently (Thu Sep 21 09:46:10 2017) [sssd[pam]] [responder_set_fd_limit] (0x0100): Maximum file descriptors set to [8192] (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x7ff3c1a268f0:domains@default] (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sss_dp_get_domains_msg] (0x0400): Sending get domains request for [default][] (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x7ff3c2191be0 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x7ff3c1a268f0:domains@default] (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x7ff3c2188be0 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x7ff3c2188be0 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x7ff3c218c960 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x7ff3c218c960 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x7ff3c218c960 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000): 0x7ff3c218b0c0/0x7ff3c2188050 (17), R/- (disabled) (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000): 0x7ff3c218b0c0/0x7ff3c2189c40 (17), -/W (enabled) (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000): 0x7ff3c218d6c0/0x7ff3c218c4e0 (18), R/- (disabled) (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000): 0x7ff3c218d6c0/0x7ff3c218c490 (18), -/W (enabled) (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000): 0x7ff3c218b0c0/0x7ff3c2188050 (17), R/- (enabled) (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000): 0x7ff3c218b0c0/0x7ff3c2189c40 (17), -/W (disabled) (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000): 0x7ff3c218d6c0/0x7ff3c218c4e0 (18), R/- (enabled) (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000): 0x7ff3c218d6c0/0x7ff3c218c490 (18), -/W (disabled) (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000): 0x7ff3c218b0c0/0x7ff3c2188050 (17), R/- (disabled) (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000): 0x7ff3c218b0c0/0x7ff3c2189c40 (17), -/W (enabled) (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000): 0x7ff3c218d6c0/0x7ff3c218c4e0 (18), R/- (disabled) (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000): 0x7ff3c218d6c0/0x7ff3c218c490 (18), -/W (enabled) (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000): 0x7ff3c218b0c0/0x7ff3c2188050 (17), R/- (enabled) (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000): 0x7ff3c218b0c0/0x7ff3c2189c40 (17), -/W (disabled) (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000): 0x7ff3c218d6c0/0x7ff3c218c4e0 (18), R/- (enabled) (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000): 0x7ff3c218d6c0/0x7ff3c218c490 (18), -/W (disabled) (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x7ff3c218b760 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x7ff3c2188be0 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_dispatch] (0x4000): Dispatching. (Thu Sep 21 09:46:10 2017) [sssd[pam]] [id_callback] (0x0100): Got id ack and version (1) from Monitor (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x7ff3c218de70 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x7ff3c2191be0 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x7ff3c218c960 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_dispatch] (0x4000): Dispatching. (Thu Sep 21 09:46:10 2017) [sssd[pam]] [rdp_process_pending_call] (0x0400): DP Success (Thu Sep 21 09:46:10 2017) [sssd[pam]] [rdp_register_client_done] (0x0400): Client is registered with DP (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x7ff3c218c960 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_dispatch] (0x4000): Dispatching. (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sss_dp_get_reply] (0x0010): The Data Provider returned an error [org.freedesktop.sssd.Error.DataProvider.NotSupported] (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7ff3c2192a80 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7ff3c2194cc0 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Running timer event 0x7ff3c2192a80 "ltdb_callback" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer event 0x7ff3c2194cc0 "ltdb_timeout" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x7ff3c2192a80 "ltdb_callback" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7ff3c21929c0 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7ff3c2192a80 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Running timer event 0x7ff3c21929c0 "ltdb_callback" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer event 0x7ff3c2192a80 "ltdb_timeout" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x7ff3c21929c0 "ltdb_callback" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7ff3c218e1a0 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7ff3c218bcc0 (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Running timer event 0x7ff3c218e1a0 "ltdb_callback" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer event 0x7ff3c218bcc0 "ltdb_timeout" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x7ff3c218e1a0 "ltdb_callback" (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'root' matched without domain, user is root (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sss_ncache_set_str] (0x0400): Adding [NCE/USER/default/root@default] to negative cache permanently (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'root' matched without domain, user is root (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sss_ncache_set_str] (0x0400): Adding [NCE/GROUP/default/root@default] to negative cache permanently (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x7ff3c1a268f0:domains@default] (Thu Sep 21 09:46:10 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x7ff3c218c960 (Thu Sep 21 09:46:27 2017) [sssd[pam]] [get_client_cred] (0x4000): Client creds: euid[0] egid[513] pid[17575]. (Thu Sep 21 09:46:27 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7ff3c2195210][23] (Thu Sep 21 09:46:27 2017) [sssd[pam]] [accept_fd_handler] (0x0400): Client connected! (Thu Sep 21 09:46:27 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7ff3c2195210][23] (Thu Sep 21 09:46:27 2017) [sssd[pam]] [sss_cmd_get_version] (0x0200): Received client version [3]. (Thu Sep 21 09:46:27 2017) [sssd[pam]] [sss_cmd_get_version] (0x0200): Offered version [3]. (Thu Sep 21 09:46:27 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7ff3c2195210][23] (Thu Sep 21 09:46:27 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7ff3c2195210][23] (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_cmd_authenticate] (0x0100): entering pam_cmd_authenticate (Thu Sep 21 09:46:27 2017) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'test2user' matched without domain, user is test2user (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_print_data] (0x0100): command: SSS_PAM_AUTHENTICATE (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_print_data] (0x0100): domain: not set (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_print_data] (0x0100): user: test2user (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_print_data] (0x0100): service: su-l (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: pts/0 (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser: heller (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 1 (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0 (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 0 (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 17575 (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_print_data] (0x0100): logon name: test2user (Thu Sep 21 09:46:27 2017) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/default/test2user@default] (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_initgr_check_timeout] (0x4000): User [test2user] not found in PAM cache. (Thu Sep 21 09:46:27 2017) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x7ff3c1a25030:3:test2user@default@default] (Thu Sep 21 09:46:27 2017) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [default][0x3][BE_REQ_INITGROUPS][1][name=test2user@default:-] (Thu Sep 21 09:46:27 2017) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x7ff3c21880a0 (Thu Sep 21 09:46:27 2017) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x7ff3c1a25030:3:test2user@default@default] (Thu Sep 21 09:46:27 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x7ff3c21880a0 (Thu Sep 21 09:46:27 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x7ff3c218c960 (Thu Sep 21 09:46:27 2017) [sssd[pam]] [sbus_dispatch] (0x4000): Dispatching. (Thu Sep 21 09:46:27 2017) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [test2user@default] (Thu Sep 21 09:46:27 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7ff3c2196940 (Thu Sep 21 09:46:27 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7ff3c219a2d0 (Thu Sep 21 09:46:27 2017) [sssd[pam]] [ldb] (0x4000): Running timer event 0x7ff3c2196940 "ltdb_callback" (Thu Sep 21 09:46:27 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer event 0x7ff3c219a2d0 "ltdb_timeout" (Thu Sep 21 09:46:27 2017) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x7ff3c2196940 "ltdb_callback" (Thu Sep 21 09:46:27 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7ff3c2195d40 (Thu Sep 21 09:46:27 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7ff3c219bb90 (Thu Sep 21 09:46:27 2017) [sssd[pam]] [ldb] (0x4000): Running timer event 0x7ff3c2195d40 "ltdb_callback" (Thu Sep 21 09:46:27 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer event 0x7ff3c219bb90 "ltdb_timeout" (Thu Sep 21 09:46:27 2017) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x7ff3c2195d40 "ltdb_callback" (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [test2user@default@default] (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pd_set_primary_name] (0x0400): User's primary name is test2user@default (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [test2user] added to PAM initgroup cache (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data: (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_print_data] (0x0100): command: SSS_PAM_AUTHENTICATE (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_print_data] (0x0100): domain: default (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_print_data] (0x0100): user: test2user@default (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_print_data] (0x0100): service: su-l (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: pts/0 (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser: heller (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 1 (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0 (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 0 (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 17575 (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_print_data] (0x0100): logon name: test2user (Thu Sep 21 09:46:27 2017) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x7ff3c218b760 (Thu Sep 21 09:46:27 2017) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0 (Thu Sep 21 09:46:27 2017) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x7ff3c1a25030:3:test2user@default@default] (Thu Sep 21 09:46:28 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x7ff3c218b760 (Thu Sep 21 09:46:28 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x7ff3c218c960 (Thu Sep 21 09:46:28 2017) [sssd[pam]] [sbus_dispatch] (0x4000): Dispatching. (Thu Sep 21 09:46:28 2017) [sssd[pam]] [pam_dp_process_reply] (0x0200): received: [4 (System error)][default] (Thu Sep 21 09:46:28 2017) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [4]: System error. (Thu Sep 21 09:46:28 2017) [sssd[pam]] [pam_reply] (0x0200): blen: 24 (Thu Sep 21 09:46:28 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7ff3c2195210][23] (Thu Sep 21 09:46:28 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7ff3c2195210][23] (Thu Sep 21 09:46:28 2017) [sssd[pam]] [client_recv] (0x0200): Client disconnected! (Thu Sep 21 09:46:28 2017) [sssd[pam]] [client_close_fn] (0x2000): Terminated client [0x7ff3c2195210][23] (Thu Sep 21 09:46:32 2017) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [test2user] removed from PAM initgroup cache (Thu Sep 21 09:46:33 2017) [sssd[pam]] [get_client_cred] (0x4000): Client creds: euid[0] egid[513] pid[17576]. (Thu Sep 21 09:46:33 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7ff3c219bae0][23] (Thu Sep 21 09:46:33 2017) [sssd[pam]] [accept_fd_handler] (0x0400): Client connected! (Thu Sep 21 09:46:33 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7ff3c219bae0][23] (Thu Sep 21 09:46:33 2017) [sssd[pam]] [sss_cmd_get_version] (0x0200): Received client version [3]. (Thu Sep 21 09:46:33 2017) [sssd[pam]] [sss_cmd_get_version] (0x0200): Offered version [3]. (Thu Sep 21 09:46:33 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7ff3c219bae0][23] (Thu Sep 21 09:46:33 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7ff3c219bae0][23] (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_cmd_authenticate] (0x0100): entering pam_cmd_authenticate (Thu Sep 21 09:46:33 2017) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'test2user' matched without domain, user is test2user (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_print_data] (0x0100): command: SSS_PAM_AUTHENTICATE (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_print_data] (0x0100): domain: not set (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_print_data] (0x0100): user: test2user (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_print_data] (0x0100): service: su-l (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: pts/0 (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser: heller (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 1 (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0 (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 0 (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 17576 (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_print_data] (0x0100): logon name: test2user (Thu Sep 21 09:46:33 2017) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/default/test2user@default] (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_initgr_check_timeout] (0x4000): User [test2user] not found in PAM cache. (Thu Sep 21 09:46:33 2017) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x7ff3c1a25030:3:test2user@default@default] (Thu Sep 21 09:46:33 2017) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [default][0x3][BE_REQ_INITGROUPS][1][name=test2user@default:-] (Thu Sep 21 09:46:33 2017) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x7ff3c2195280 (Thu Sep 21 09:46:33 2017) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x7ff3c1a25030:3:test2user@default@default] (Thu Sep 21 09:46:33 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x7ff3c2195280 (Thu Sep 21 09:46:33 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x7ff3c218c960 (Thu Sep 21 09:46:33 2017) [sssd[pam]] [sbus_dispatch] (0x4000): Dispatching. (Thu Sep 21 09:46:33 2017) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [test2user@default] (Thu Sep 21 09:46:33 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7ff3c21967d0 (Thu Sep 21 09:46:33 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7ff3c2196890 (Thu Sep 21 09:46:33 2017) [sssd[pam]] [ldb] (0x4000): Running timer event 0x7ff3c21967d0 "ltdb_callback" (Thu Sep 21 09:46:33 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer event 0x7ff3c2196890 "ltdb_timeout" (Thu Sep 21 09:46:33 2017) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x7ff3c21967d0 "ltdb_callback" (Thu Sep 21 09:46:33 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7ff3c2191330 (Thu Sep 21 09:46:33 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7ff3c218fd10 (Thu Sep 21 09:46:33 2017) [sssd[pam]] [ldb] (0x4000): Running timer event 0x7ff3c2191330 "ltdb_callback" (Thu Sep 21 09:46:33 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer event 0x7ff3c218fd10 "ltdb_timeout" (Thu Sep 21 09:46:33 2017) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x7ff3c2191330 "ltdb_callback" (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [test2user@default@default] (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pd_set_primary_name] (0x0400): User's primary name is test2user@default (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [test2user] added to PAM initgroup cache (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data: (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_print_data] (0x0100): command: SSS_PAM_AUTHENTICATE (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_print_data] (0x0100): domain: default (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_print_data] (0x0100): user: test2user@default (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_print_data] (0x0100): service: su-l (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: pts/0 (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser: heller (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 1 (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0 (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 0 (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 17576 (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_print_data] (0x0100): logon name: test2user (Thu Sep 21 09:46:33 2017) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x7ff3c21952c0 (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0 (Thu Sep 21 09:46:33 2017) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x7ff3c1a25030:3:test2user@default@default] (Thu Sep 21 09:46:33 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x7ff3c21952c0 (Thu Sep 21 09:46:33 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x7ff3c218c960 (Thu Sep 21 09:46:33 2017) [sssd[pam]] [sbus_dispatch] (0x4000): Dispatching. (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_dp_process_reply] (0x0200): received: [4 (System error)][default] (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [4]: System error. (Thu Sep 21 09:46:33 2017) [sssd[pam]] [pam_reply] (0x0200): blen: 24 (Thu Sep 21 09:46:33 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7ff3c219bae0][23] (Thu Sep 21 09:46:33 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7ff3c219bae0][23] (Thu Sep 21 09:46:33 2017) [sssd[pam]] [client_recv] (0x0200): Client disconnected! (Thu Sep 21 09:46:33 2017) [sssd[pam]] [client_close_fn] (0x2000): Terminated client [0x7ff3c219bae0][23] (Thu Sep 21 09:46:38 2017) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [test2user] removed from PAM initgroup cache (Thu Sep 21 09:47:09 2017) [sssd[pam]] [get_client_cred] (0x4000): Client creds: euid[0] egid[513] pid[17586]. (Thu Sep 21 09:47:09 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7ff3c21915e0][23] (Thu Sep 21 09:47:09 2017) [sssd[pam]] [accept_fd_handler] (0x0400): Client connected! (Thu Sep 21 09:47:09 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7ff3c21915e0][23] (Thu Sep 21 09:47:09 2017) [sssd[pam]] [sss_cmd_get_version] (0x0200): Received client version [3]. (Thu Sep 21 09:47:09 2017) [sssd[pam]] [sss_cmd_get_version] (0x0200): Offered version [3]. (Thu Sep 21 09:47:09 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7ff3c21915e0][23] (Thu Sep 21 09:47:09 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7ff3c21915e0][23] (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_cmd_authenticate] (0x0100): entering pam_cmd_authenticate (Thu Sep 21 09:47:09 2017) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'test2user' matched without domain, user is test2user (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_print_data] (0x0100): command: SSS_PAM_AUTHENTICATE (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_print_data] (0x0100): domain: not set (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_print_data] (0x0100): user: test2user (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_print_data] (0x0100): service: su-l (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: pts/0 (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser: heller (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 1 (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0 (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 0 (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 17586 (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_print_data] (0x0100): logon name: test2user (Thu Sep 21 09:47:09 2017) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/default/test2user@default] (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_initgr_check_timeout] (0x4000): User [test2user] not found in PAM cache. (Thu Sep 21 09:47:09 2017) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x7ff3c1a25030:3:test2user@default@default] (Thu Sep 21 09:47:09 2017) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [default][0x3][BE_REQ_INITGROUPS][1][name=test2user@default:-] (Thu Sep 21 09:47:09 2017) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x7ff3c218a2b0 (Thu Sep 21 09:47:09 2017) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x7ff3c1a25030:3:test2user@default@default] (Thu Sep 21 09:47:09 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x7ff3c218a2b0 (Thu Sep 21 09:47:09 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x7ff3c218c960 (Thu Sep 21 09:47:09 2017) [sssd[pam]] [sbus_dispatch] (0x4000): Dispatching. (Thu Sep 21 09:47:09 2017) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [test2user@default] (Thu Sep 21 09:47:09 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7ff3c218da40 (Thu Sep 21 09:47:09 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7ff3c218db00 (Thu Sep 21 09:47:09 2017) [sssd[pam]] [ldb] (0x4000): Running timer event 0x7ff3c218da40 "ltdb_callback" (Thu Sep 21 09:47:09 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer event 0x7ff3c218db00 "ltdb_timeout" (Thu Sep 21 09:47:09 2017) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x7ff3c218da40 "ltdb_callback" (Thu Sep 21 09:47:09 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7ff3c219bb60 (Thu Sep 21 09:47:09 2017) [sssd[pam]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7ff3c219b940 (Thu Sep 21 09:47:09 2017) [sssd[pam]] [ldb] (0x4000): Running timer event 0x7ff3c219bb60 "ltdb_callback" (Thu Sep 21 09:47:09 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer event 0x7ff3c219b940 "ltdb_timeout" (Thu Sep 21 09:47:09 2017) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x7ff3c219bb60 "ltdb_callback" (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [test2user@default@default] (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pd_set_primary_name] (0x0400): User's primary name is test2user@default (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [test2user] added to PAM initgroup cache (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data: (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_print_data] (0x0100): command: SSS_PAM_AUTHENTICATE (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_print_data] (0x0100): domain: default (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_print_data] (0x0100): user: test2user@default (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_print_data] (0x0100): service: su-l (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: pts/0 (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser: heller (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 1 (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0 (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 0 (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 17586 (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_print_data] (0x0100): logon name: test2user (Thu Sep 21 09:47:09 2017) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x7ff3c2190d20 (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0 (Thu Sep 21 09:47:09 2017) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x7ff3c1a25030:3:test2user@default@default] (Thu Sep 21 09:47:09 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x7ff3c2190d20 (Thu Sep 21 09:47:09 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x7ff3c218c960 (Thu Sep 21 09:47:09 2017) [sssd[pam]] [sbus_dispatch] (0x4000): Dispatching. (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_dp_process_reply] (0x0200): received: [4 (System error)][default] (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [4]: System error. (Thu Sep 21 09:47:09 2017) [sssd[pam]] [pam_reply] (0x0200): blen: 24 (Thu Sep 21 09:47:09 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7ff3c21915e0][23] (Thu Sep 21 09:47:09 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7ff3c21915e0][23] (Thu Sep 21 09:47:09 2017) [sssd[pam]] [client_recv] (0x0200): Client disconnected! (Thu Sep 21 09:47:09 2017) [sssd[pam]] [client_close_fn] (0x2000): Terminated client [0x7ff3c21915e0][23] (Thu Sep 21 09:47:14 2017) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [test2user] removed from PAM initgroup cache
What am I missing here?
At Wed, 20 Sep 2017 19:30:17 +0200 Dieter =?UTF-8?B?S2zDvG50ZXI=?= dieter@dkluenter.de wrote:
Am Wed, 20 Sep 2017 12:32:37 -0400 (EDT) schrieb Robert Heller heller@deepsoft.com:
OK, I fixed the ACLs (I think), but it is still not working. I turned on verbose debugging for sssd[pam] and moderate debugging for slapd. =20 Here are my ACLs in /etc/openldap/slapd.d/cn=3Dconfig/olcDatabase=3D{2}hdb.ldif: =20 olcAccess: {0}to attrs=3DuserPassword by self write by anonymous auth by dn=3Duid=3Dheller,ou=3DPeople,dc=3Ddeepsoft,dc=3Dcom write by * none olcAccess: {1}to * by dn=3Duid=3Dheller,ou=3DPeople,dc=3Ddeepsoft,dc=3Dcom write by * read =20 There are also these olcAccess entries: =20 in /etc/openldap/slapd.d/cn=3Dconfig/olcDatabase=3D{0}config.ldif: =20 olcAccess: {0}to * by dn.base=3D"gidNumber=3D0+uidNumber=3D0,cn=3Dpeercred,cn=3Dextern al,cn=3D=
auth"
manage by * none =20 and in /etc/openldap/slapd.d/cn=3Dconfig/olcDatabase=3D{1}monitor.ldif: =20 olcAccess: {0}to * by dn.base=3D"gidNumber=3D0+uidNumber=3D0,cn=3Dpeercred,cn=3Dextern al,cn=3D=
auth"
read by dn.base=3D"cn=3DManager,dc=3Ddeepsoft,dc=3Dcom" read by * none
[...]
You may run slapd in debugging mode 128.
-Dieter
--=20 Dieter Kl=C3=BCnter | Systemberatung http://sys4.de GPG Key ID: E9ED159B 53=C2=B037'09,95"N 10=C2=B008'02,42"E