Bug 42616

Summary: Handler module is not called if an object is moved to a non-readable position
Product: UCS Test Reporter: Stefan Gohmann <gohmann>
Component: LDAPAssignee: UCS maintainers <ucs-maintainers>
Status: NEW --- QA Contact:
Severity: normal    
Priority: P5    
Version: unspecified   
Target Milestone: ---   
Hardware: Other   
OS: Linux   
What kind of report is it?: Development Internal What type of bug is this?: ---
Who will be affected by this bug?: --- How will those affected feel about the bug?: ---
User Pain: Enterprise Customer affected?:
School Customer affected?: ISV affected?:
Waiting Support: Flags outvoted (downgraded) after PO Review:
Ticket number: Bug group (optional):
Max CVSS v3 score:
Bug Depends on: 32685, 42029    
Bug Blocks:    

Description Stefan Gohmann univentionstaff 2016-10-10 13:29:22 CEST 
A test case should be added. See also Bug #32685.

+++ This bug was initially created as a clone of Bug #42029 +++

The listener doesn't call a listener module twice if an object is moved to a non-readable position. It works only with the replication module.

From the logs:
------------------------------------------------------------------------------
17.08.16 02:27:31.889  LISTENER    ( INFO    ) : notifier returned = id: 18220803       dn: uid=tester1,cn=schueler,cn=users,ou=305,dc=foo,dc=bar   cmd: r
17.08.16 02:27:31.889  LISTENER    ( INFO    ) : updating 'uid=tester1,cn=schueler,cn=users,ou=305,dc=foo,dc=bar' command r
17.08.16 02:27:31.899  LISTENER    ( INFO    ) : delete handlers for uid=tester1,cn=schueler,cn=users,ou=305,dc=foo,dc=bar
17.08.16 02:27:31.899  LISTENER    ( INFO    ) : replication: Running handler r for: uid=tester1,cn=schueler,cn=users,ou=305,dc=foo,dc=bar
17.08.16 02:27:31.899  LISTENER    ( PROCESS ) : replication: rename phase I: uid=tester1,cn=schueler,cn=users,ou=305,dc=foo,dc=bar (entryUUID=d56aa1e8-ef9c-1035-9045-c19db182a655)
17.08.16 02:27:31.905  LISTENER    ( INFO    ) : handler: replication (successful)
[...]
17.08.16 02:27:31.914  LISTENER    ( INFO    ) : handler: s4-connector (successful)
[...]
17.08.16 02:27:31.915  LISTENER    ( WARN    ) : at least one delete handler failed
17.08.16 02:27:31.975  LISTENER    ( INFO    ) : delete handlers for uid=tester1,cn=schueler,cn=users,ou=305,dc=foo,dc=bar
17.08.16 02:27:31.975  LISTENER    ( INFO    ) : replication: Running handler r for: uid=tester1,cn=schueler,cn=users,ou=305,dc=foo,dc=bar
17.08.16 02:27:31.975  LISTENER    ( INFO    ) : replication: LDAP keys=['uid', 'krb5PrincipalName', 'objectClass', 'univentionMailHomeServer', 'sambaProfilePath', 'entryUUID', 'sambaAcctFlags', 'sambaPasswordHistory', 'entryDN', 'structuralObjectClass', 'krb5MaxLife', '
cn', 'hasSubordinates', 'sambaHomePath', 'gecos', 'userPassword', 'ucsschoolRecordUID', 'krb5Key', 'krb5MaxRenew', 'mail', 'uidNumber', 'departmentNumber', 'loginShell', 'univentionObjectType', 'krb5KDCFlags', 'gidNumber', 'subschemaSubentry', 'entryCSN', 'sambaPwdLastSe
t', 'sambaPrimaryGroupSID', 'sambaNTPassword', 'modifyTimestamp', 'displayName', 'mailPrimaryAddress', 'ucsschoolSchool', 'sambaHomeDrive', 'modifiersName', 'sambaSID', 'createTimestamp', 'krb5KeyVersionNumber', 'sn', 'homeDirectory', 'ucsschoolSourceUID', 'givenName', '
creatorsName']; listener keys=[]
17.08.16 02:27:31.975  LISTENER    ( INFO    ) : replication: old entries from LDAP server and Listener do not match
17.08.16 02:27:31.975  LISTENER    ( PROCESS ) : replication: rename phase I: uid=tester1,cn=schueler,cn=users,ou=305,dc=foo,dc=bar (entryUUID=d56aa1e8-ef9c-1035-9045-c19db182a655)
17.08.16 02:27:31.976  LISTENER    ( INFO    ) : handler: replication (successful)
[...]
17.08.16 02:27:31.976  LISTENER    ( INFO    ) : handler: s4-connector (skipped)
[...]
17.08.16 02:27:31.976  LISTENER    ( INFO    ) : deleted from cache: uid=tester1,cn=schueler,cn=users,ou=305,dc=foo,dc=bar
------------------------------------------------------------------------------

The s4-connector listener module is skipped. Happened at Ticket #2016080221000449