Issue #543 has been updated by Clément OUDOT.

Status changed from Feedback to Assigned
Assigned to changed from Clément OUDOT to Raphaël Ouazana

Messages still present:
<pre>
clement@ader-xps:~$ Programmes/lsc-2.1-SNAPSHOT/bin/lsc -f 
configuration/lsc/linid/openldap2ad/ -c user -t1
avr. 28 19:09:16 - INFO  - Logging configuration successfully loaded from 
/home/clement/configuration/lsc/linid/openldap2ad/logback.xml 
avr. 28 19:09:16 - INFO  - LSC configuration successfully loaded from 
/home/clement/configuration/lsc/linid/openldap2ad/
avr. 28 19:09:16 - INFO  - Connecting to LDAP server 
ldaps://ad.example.com/DC=example,DC=com as cn=demo,cn=Users,dc=example,dc=com
avr. 28 19:09:16 - INFO  - Connecting to LDAP server 
ldap://localhost/dc=example,dc=com as cn=admin,dc=example,dc=com
avr. 28 19:09:17 - INFO  - Starting clean for user
avr. 28 19:09:17 - DEBUG - Using pagedResults control for 1000 entries at a time
avr. 28 19:09:17 - ERROR - There is no future associated with operation message 
ID 3, perhaps the operation would have been completed
avr. 28 19:09:17 - ERROR - There is no future associated with operation message 
ID 3, perhaps the operation would have been completed
avr. 28 19:09:17 - INFO  - # Removing object 
CN=test,OU=users,OU=demo,DC=example,DC=com for user
# Mon Apr 28 19:09:17 CEST 2014
dn: CN=test,OU=users,OU=demo,DC=example,DC=com
changetype: delete

</pre>

ABANDON operations also visible on server side:
<pre>
Apr 28 19:09:05 ader-xps slapd[1863]: conn=1065 op=0 BIND 
dn="cn=admin,dc=example,dc=com" method=128
Apr 28 19:09:05 ader-xps slapd[1863]: conn=1065 op=0 BIND 
dn="cn=admin,dc=example,dc=com" mech=SIMPLE ssf=0
Apr 28 19:09:05 ader-xps slapd[1863]: conn=1065 op=0 RESULT tag=97 err=0 text=
Apr 28 19:09:05 ader-xps slapd[1863]: conn=1065 op=1 SRCH 
base="ou=users,dc=example,dc=com" scope=2 deref=3 
filter="(&(objectClass=inetOrgPerson)(uid=coudot))"
Apr 28 19:09:05 ader-xps slapd[1863]: conn=1065 op=1 SRCH attr=cn description 
givenName mail pwdAccountLockedTime sn uid userpassword samaccountname
Apr 28 19:09:05 ader-xps slapd[1863]: conn=1065 op=1 SEARCH RESULT tag=101 
err=0 nentries=1 text=
Apr 28 19:09:05 ader-xps slapd[1863]: conn=1065 op=2 SRCH 
base="ou=users,dc=example,dc=com" scope=2 deref=3 
filter="(&(objectClass=inetOrgPerson)(uid=xguimard))"
Apr 28 19:09:05 ader-xps slapd[1863]: conn=1065 op=2 SRCH attr=cn description 
givenName mail pwdAccountLockedTime sn uid userpassword samaccountname
Apr 28 19:09:05 ader-xps slapd[1863]: conn=1065 op=2 SEARCH RESULT tag=101 
err=0 nentries=1 text=
Apr 28 19:09:05 ader-xps slapd[1863]: conn=1065 op=3 SRCH 
base="ou=users,dc=example,dc=com" scope=2 deref=3 
filter="(&(objectClass=inetOrgPerson)(uid=test))"
Apr 28 19:09:05 ader-xps slapd[1863]: conn=1065 op=3 SRCH attr=cn description 
givenName mail pwdAccountLockedTime sn uid userpassword samaccountname
Apr 28 19:09:05 ader-xps slapd[1863]: conn=1065 op=3 SEARCH RESULT tag=101 
err=0 nentries=1 text=
Apr 28 19:09:05 ader-xps slapd[1863]: conn=1065 fd=20 closed (connection lost)
Apr 28 19:09:05 ader-xps slapd[1863]: conn=1064 fd=19 closed (connection lost)
Apr 28 19:09:13 ader-xps slapd[1863]: conn=1028 op=43 DEL 
dn="uid=test,ou=users,dc=example,dc=com"
Apr 28 19:09:14 ader-xps slapd[1863]: conn=1028 op=43 RESULT tag=107 err=0 text=
Apr 28 19:09:14 ader-xps slapd[1863]: conn=1028 op=44 SRCH 
base="ou=users,dc=example,dc=com" scope=1 deref=3 filter="(objectClass=*)"
Apr 28 19:09:14 ader-xps slapd[1863]: conn=1028 op=44 SRCH attr=hasSubordinates 
objectClass
Apr 28 19:09:14 ader-xps slapd[1863]: conn=1028 op=44 SEARCH RESULT tag=101 
err=0 nentries=2 text=
Apr 28 19:09:16 ader-xps slapd[1863]: conn=1066 fd=19 ACCEPT from 
IP=127.0.0.1:40453 (IP=0.0.0.0:389)
Apr 28 19:09:16 ader-xps slapd[1863]: conn=1066 op=0 BIND 
dn="cn=admin,dc=example,dc=com" method=128
Apr 28 19:09:16 ader-xps slapd[1863]: conn=1066 op=0 BIND 
dn="cn=admin,dc=example,dc=com" mech=SIMPLE ssf=0
Apr 28 19:09:16 ader-xps slapd[1863]: conn=1066 op=0 RESULT tag=97 err=0 text=
Apr 28 19:09:17 ader-xps slapd[1863]: conn=1067 fd=20 ACCEPT from 
IP=127.0.0.1:40454 (IP=0.0.0.0:389)
Apr 28 19:09:17 ader-xps slapd[1863]: conn=1067 op=0 BIND 
dn="cn=admin,dc=example,dc=com" method=128
Apr 28 19:09:17 ader-xps slapd[1863]: conn=1067 op=0 BIND 
dn="cn=admin,dc=example,dc=com" mech=SIMPLE ssf=0
Apr 28 19:09:17 ader-xps slapd[1863]: conn=1067 op=0 RESULT tag=97 err=0 text=
Apr 28 19:09:17 ader-xps slapd[1863]: conn=1067 op=1 SRCH 
base="ou=users,dc=example,dc=com" scope=2 deref=3 
filter="(&(objectClass=inetOrgPerson)(uid=coudot))"
Apr 28 19:09:17 ader-xps slapd[1863]: conn=1067 op=1 SRCH attr=cn description 
givenName mail pwdAccountLockedTime sn uid userpassword samaccountname
Apr 28 19:09:17 ader-xps slapd[1863]: conn=1067 op=1 SEARCH RESULT tag=101 
err=0 nentries=1 text=
Apr 28 19:09:17 ader-xps slapd[1863]: conn=1067 op=2 SRCH 
base="ou=users,dc=example,dc=com" scope=2 deref=3 
filter="(&(objectClass=inetOrgPerson)(uid=xguimard))"
Apr 28 19:09:17 ader-xps slapd[1863]: conn=1067 op=2 SRCH attr=cn description 
givenName mail pwdAccountLockedTime sn uid userpassword samaccountname
Apr 28 19:09:17 ader-xps slapd[1863]: conn=1067 op=2 SEARCH RESULT tag=101 
err=0 nentries=1 text=
Apr 28 19:09:17 ader-xps slapd[1863]: conn=1067 op=3 ABANDON msg=3
Apr 28 19:09:17 ader-xps slapd[1863]: conn=1067 op=4 SRCH 
base="ou=users,dc=example,dc=com" scope=2 deref=3 
filter="(&(objectClass=inetOrgPerson)(uid=test))"
Apr 28 19:09:17 ader-xps slapd[1863]: conn=1067 op=4 SRCH attr=cn description 
givenName mail pwdAccountLockedTime sn uid userpassword samaccountname
Apr 28 19:09:17 ader-xps slapd[1863]: conn=1067 op=4 SEARCH RESULT tag=101 
err=0 nentries=0 text=
Apr 28 19:09:17 ader-xps slapd[1863]: conn=1067 fd=20 closed (connection lost)
Apr 28 19:09:17 ader-xps slapd[1863]: conn=1066 fd=19 closed (connection lost)

</pre>
----------------------------------------
Bug #543: Clean phase with asyncLdapSourceService
http://tools.lsc-project.org/issues/543

Author: Clément OUDOT
Status: Assigned
Priority: Low
Assigned to: Raphaël Ouazana
Category: Core
Target version: 2.1
Problem in version: 


Not a major issue, but may be interesting to look at it.

When launching the clean phase for an asyncLdapSourceService, we have a message 
like this for each search on the source directory :
<pre>
nov. 15 10:40:02 - ERROR - There is no future associated with operation message 
ID 30073, perhaps the operation would have been completed
</pre>

And this in OpenLDAP log:
<pre>
Nov 15 10:37:35 qlf-mldap-v01 slapd[2594]: conn=1149076 op=2783 SRCH 
base="ou=people,ou=XXX" scope=2 deref=3 filter="(&(objectClass=XXX)(XXX=AAA))"
Nov 15 10:37:35 qlf-mldap-v01 slapd[2594]: conn=1149076 op=2783 SRCH attr=cn sn 
uid displayName givenName XXX
Nov 15 10:37:35 qlf-mldap-v01 slapd[2594]: conn=1149076 op=2783 SEARCH RESULT 
tag=101 err=0 nentries=1 text=
Nov 15 10:37:35 qlf-mldap-v01 slapd[2594]: conn=1149076 op=2784 ABANDON msg=2784
</pre>

What is strange, is that the ABANDON message is not done on the previous 
operation (2783) but on the ABANDON message itself (2784).


Other remark, the clean phase do a search on source directory with all 
attributs configured. It seems not very usefull as we only need to know if the 
entry exists to delete the destination entry.


-- 
You have received this notification because you have either subscribed to it, 
or are involved in it.
To change your notification preferences, please click here: 
http://tools.lsc-project.org/my/account
_______________________________________________________________
Ldap Synchronization Connector (LSC) - http://lsc-project.org

lsc-dev mailing list
[email protected]
http://lists.lsc-project.org/listinfo/lsc-dev

Reply via email to