li created DIRSERVER-2251:
-----------------------------
Summary: ERR_54 error in a modification sync message handling
Key: DIRSERVER-2251
URL: https://issues.apache.org/jira/browse/DIRSERVER-2251
Project: Directory ApacheDS
Issue Type: Bug
Affects Versions: 2.0.0.AM25
Environment: the consumer just config with oney one
attribute(userPassword).
when master instance change the userPassword value, it will happen.
Reporter: li
13:22:44] DEBUG [org.apache.directory.server.CONSUMER_LOG] -
.................... END handleSyncInfo ...............
[13:22:49] DEBUG [org.apache.directory.server.CONSUMER_LOG] - PING : The
consumer 0 is alive
[13:22:54] DEBUG [org.apache.directory.server.CONSUMER_LOG] - PING : The
consumer 0 is alive
[13:22:59] DEBUG [org.apache.directory.server.CONSUMER_LOG] - PING : The
consumer 0 is alive
[13:23:01] DEBUG [org.apache.directory.api.CODEC_LOG] - MSG_14000_DECODING_PDU
()
[13:23:01] DEBUG [org.apache.directory.api.CODEC_LOG] - 0x30 0x82 0x03 0x7D
0x02 0x01 0x02 0x64 0x82 0x03 0x09 0x04 0x17 0x75 0x69 0x64 0x3D 0x32 0x2C 0x64
0x63 0x3D 0x65 0x78 0x61 0x6D 0x70 0x6C 0x65 0x2C 0x64 0x63 0x3D 0x63 0x6F 0x6D
0x30 0x82 0x02 0xEC 0x30 0x36 0x04 0x08 0x65 0x6E 0x74 0x72 0x79 0x43 0x53 0x4E
0x31 0x2A 0x04 0x28 0x32 0x30 0x31 0x38 0x30 0x39 0x31 0x36 0x30 0x35 0x32 0x33
0x30 0x31 0x2E 0x39 0x31 0x35 0x30 0x30 0x30 0x5A 0x23 0x30 0x30 0x30 0x30 0x30
0x30 0x23 0x30 0x30 0x31 0x23 0x30 0x30 0x30 0x30 0x30 0x30 0x30 0x45 0x04 0x0C
0x63 0x72 0x65 0x61 0x74 0x6F 0x72 0x73 0x4E 0x61 0x6D 0x65 0x31 0x35 0x04 0x33
0x30 0x2E 0x39 0x2E 0x32 0x33 0x34 0x32 0x2E 0x31 0x39 0x32 0x30 0x30 0x33 0x30
0x30 0x2E 0x31 0x30 0x30 0x2E 0x31 0x2E 0x31 0x3D 0x20 0x61 0x64 0x6D 0x69 0x6E
0x20 0x2C 0x32 0x2E 0x35 0x2E 0x34 0x2E 0x31 0x31 0x3D 0x20 0x73 0x79 0x73 0x74
0x65 0x6D 0x20 0x30 0x28 0x04 0x0F 0x6D 0x6F 0x64 0x69 0x66 0x79 0x54 0x69 0x6D
0x65 0x73 0x74 0x61 0x6D 0x70 0x31 0x15 0x04 0x13 0x32 0x30 0x31 0x38 0x30 0x39
0x31 0x36 0x30 0x35 0x32 0x33 0x30 0x31 0x2E 0x39 0x31 0x35 0x5A 0x30 0x24 0x04
0x07 0x65 0x6E 0x74 0x72 0x79 0x44 0x4E 0x31 0x19 0x04 0x17 0x75 0x69 0x64 0x3D
0x32 0x2C 0x64 0x63 0x3D 0x65 0x78 0x61 0x6D 0x70 0x6C 0x65 0x2C 0x64 0x63 0x3D
0x63 0x6F 0x6D 0x30 0x46 0x04 0x0D 0x6D 0x6F 0x64 0x69 0x66 0x69 0x65 0x72 0x73
0x4E 0x61 0x6D 0x65 0x31 0x35 0x04 0x33 0x30 0x2E 0x39 0x2E 0x32 0x33 0x34 0x32
0x2E 0x31 0x39 0x32 0x30 0x30 0x33 0x30 0x30 0x2E 0x31 0x30 0x30 0x2E 0x31 0x2E
0x31 0x3D 0x20 0x61 0x64 0x6D 0x69 0x6E 0x20 0x2C 0x32 0x2E 0x35 0x2E 0x34 0x2E
0x31 0x31 0x3D 0x20 0x73 0x79 0x73 0x74 0x65 0x6D 0x20 0x30 0x33 0x04 0x09 0x65
0x6E 0x74 0x72 0x79 0x55 0x55 0x49 0x44 0x31 0x26 0x04 0x24 0x66 0x65 0x36 0x33
0x65 0x66 0x35 0x33 0x2D 0x38 0x35 0x34 0x37 0x2D 0x34 0x61 0x63 0x63 0x2D 0x39
0x32 0x63 0x37 0x2D 0x32 0x63 0x65 0x30 0x30 0x35 0x63 0x30 0x66 0x63 0x31 0x34
0x30 0x09 0x04 0x02 0x73 0x6E 0x31 0x03 0x04 0x01 0x31 0x30 0x09 0x04 0x02 0x63
0x6E 0x31 0x03 0x04 0x01 0x31 0x30 0x81 0x8D 0x04 0x0A 0x70 0x77 0x64 0x48 0x69
0x73 0x74 0x6F 0x72 0x79 0x31 0x7F 0x04 0x7D 0x32 0x30 0x31 0x38 0x30 0x39 0x31
0x36 0x30 0x35 0x31 0x32 0x35 0x35 0x2E 0x33 0x33 0x35 0x5A 0x23 0x31 0x2E 0x33
0x2E 0x36 0x2E 0x31 0x2E 0x34 0x2E 0x31 0x2E 0x31 0x34 0x36 0x36 0x2E 0x31 0x31
0x35 0x2E 0x31 0x32 0x31 0x2E 0x31 0x2E 0x34 0x30 0x23 0x37 0x32 0x23 0x65 0x31
0x4E 0x49 0x51 0x54 0x49 0x31 0x4E 0x6E 0x31 0x77 0x62 0x56 0x64 0x72 0x56 0x31
0x4E 0x43 0x51 0x30 0x77 0x31 0x4D 0x55 0x4A 0x6D 0x61 0x32 0x68 0x75 0x4E 0x7A
0x6C 0x34 0x55 0x48 0x56 0x4C 0x51 0x6B 0x74 0x49 0x65 0x69 0x38 0x76 0x53 0x44
0x5A 0x43 0x4B 0x32 0x31 0x5A 0x4E 0x6B 0x63 0x35 0x4C 0x32 0x56 0x70 0x5A 0x58
0x56 0x4E 0x50 0x51 0x3D 0x3D 0x30 0x41 0x04 0x0B 0x6F 0x62 0x6A 0x65 0x63 0x74
0x43 0x6C 0x61 0x73 0x73 0x31 0x32 0x04 0x03 0x74 0x6F 0x70 0x04 0x0D 0x69 0x6E
0x65 0x74 0x4F 0x72 0x67 0x50 0x65 0x72 0x73 0x6F 0x6E 0x04 0x06 0x70 0x65 0x72
0x73 0x6F 0x6E 0x04 0x14 0x6F 0x72 0x67 0x61 0x6E 0x69 0x7A 0x61 0x74 0x69 0x6F
0x6E 0x61 0x6C 0x50 0x65 0x72 0x73 0x6F 0x6E 0x30 0x28 0x04 0x0F 0x63 0x72 0x65
0x61 0x74 0x65 0x54 0x69 0x6D 0x65 0x73 0x74 0x61 0x6D 0x70 0x31 0x15 0x04 0x13
0x32 0x30 0x31 0x38 0x30 0x39 0x31 0x36 0x30 0x35 0x31 0x32 0x35 0x35 0x2E 0x33
0x33 0x35 0x5A 0x30 0x46 0x04 0x0C 0x75 0x73 0x65 0x72 0x50 0x61 0x73 0x73 0x77
0x6F 0x72 0x64 0x31 0x36 0x04 0x34 0x7B 0x53 0x48 0x41 0x32 0x35 0x36 0x7D 0x6C
0x73 0x72 0x6A 0x58 0x4F 0x69 0x70 0x73 0x43 0x52 0x42 0x65 0x4C 0x38 0x6F 0x35
0x4A 0x5A 0x73 0x4C 0x4F 0x47 0x34 0x4F 0x46 0x63 0x6A 0x71 0x57 0x70 0x72 0x67
0x34 0x68 0x59 0x7A 0x64 0x62 0x4B 0x43 0x68 0x34 0x3D 0x30 0x0A 0x04 0x03 0x75
0x69 0x64 0x31 0x03 0x04 0x01 0x32 0x30 0x37 0x04 0x0D 0x65 0x6E 0x74 0x72 0x79
0x50 0x61 0x72 0x65 0x6E 0x74 0x49 0x64 0x31 0x26 0x04 0x24 0x32 0x65 0x34 0x30
0x63 0x61 0x30 0x65 0x2D 0x36 0x30 0x38 0x31 0x2D 0x34 0x63 0x30 0x39 0x2D 0x39
0x61 0x30 0x66 0x2D 0x32 0x64 0x62 0x63 0x38 0x30 0x65 0x35 0x32 0x34 0x63 0x63
0xA0 0x6B 0x30 0x69 0x04 0x18 0x31 0x2E 0x33 0x2E 0x36 0x2E 0x31 0x2E 0x34 0x2E
0x31 0x2E 0x34 0x32 0x30 0x33 0x2E 0x31 0x2E 0x39 0x2E 0x31 0x2E 0x32 0x04 0x4D
0x30 0x4B 0x0A 0x01 0x02 0x04 0x10 0xFE 0x63 0xEF 0x53 0x85 0x47 0x4A 0xCC 0x92
0xC7 0x2C 0xE0 0x05 0xC0 0xFC 0x14 0x04 0x34 0x72 0x69 0x64 0x3D 0x30 0x30 0x36
0x2C 0x63 0x73 0x6E 0x3D 0x32 0x30 0x31 0x38 0x30 0x39 0x31 0x36 0x30 0x35 0x32
0x33 0x30 0x31 0x2E 0x39 0x31 0x35 0x30 0x30 0x30 0x5A 0x23 0x30 0x30 0x30 0x30
0x30 0x30 0x23 0x30 0x30 0x31 0x23 0x30 0x30 0x30 0x30 0x30 0x30
[13:23:01] DEBUG [org.apache.directory.api.CODEC_LOG] -
MSG_14002_DECODED_LDAP_MESSAGE (MessageType : SEARCH_RESULT_ENTRY
Message ID : 2
Search Result Entry
Entry
dn: uid=2,dc=example,dc=com
objectClass: top
objectClass: inetOrgPerson
objectClass: person
objectClass: organizationalPerson
cn: 1
creatorsName: 0.9.2342.19200300.100.1.1= admin ,2.5.4.11= system
modifiersName: 0.9.2342.19200300.100.1.1= admin ,2.5.4.11= system
userPassword: 0x7B 0x53 0x48 0x41 0x32 0x35 0x36 0x7D 0x6C 0x73 0x72 0x6A 0x58
0x4F 0x69 0x70 ...
entryUUID: fe63ef53-8547-4acc-92c7-2ce005c0fc14
pwdHistory:
20180916051255.335Z#1.3.6.1.4.1.1466.115.121.1.40#72#e1NIQTI1Nn1wbVdrV1NCQ0w1MUJma2huNzl4UHVLQktIei8vSDZCK21ZNkc5L2VpZXVNPQ==
uid: 2
entryParentId: 2e40ca0e-6081-4c09-9a0f-2dbc80e524cc
createTimestamp: 20180916051255.335Z
entryCSN: 20180916052301.915000Z#000000#001#000000
sn: 1
modifyTimestamp: 20180916052301.915Z
entryDN: uid=2,dc=example,dc=com
SyncStateValue control :
oid : 1.3.6.1.4.1.4203.1.9.1.2
critical : false
syncStateType : 'MODIFY'
entryUUID : '0xFE 0x63 0xEF 0x53 0x85 0x47 0x4A 0xCC 0x92 0xC7 0x2C 0xE0 0x05
0xC0 0xFC 0x14 '
cookie : '0x72 0x69 0x64 0x3D 0x30 0x30 0x36 0x2C 0x63 0x73 0x6E 0x3D 0x32
0x30 0x31 0x38 0x30 0x39 0x31 0x36 0x30 0x35 0x32 0x33 0x30 0x31 0x2E 0x39 0x31
0x35 0x30 0x30 0x30 0x5A 0x23 0x30 0x30 0x30 0x30 0x30 0x30 0x23 0x30 0x30 0x31
0x23 0x30 0x30 0x30 0x30 0x30 0x30 '
)
[13:23:01] DEBUG [org.apache.directory.ldap.client.api.LdapNetworkConnection] -
MSG_04142_MESSAGE_RECEIVED (MessageType : SEARCH_RESULT_ENTRY
Message ID : 2
Search Result Entry
Entry
dn: uid=2,dc=example,dc=com
objectClass: top
objectClass: inetOrgPerson
objectClass: person
objectClass: organizationalPerson
cn: 1
creatorsName: 0.9.2342.19200300.100.1.1= admin ,2.5.4.11= system
modifiersName: 0.9.2342.19200300.100.1.1= admin ,2.5.4.11= system
userPassword: 0x7B 0x53 0x48 0x41 0x32 0x35 0x36 0x7D 0x6C 0x73 0x72 0x6A 0x58
0x4F 0x69 0x70 ...
entryUUID: fe63ef53-8547-4acc-92c7-2ce005c0fc14
pwdHistory:
20180916051255.335Z#1.3.6.1.4.1.1466.115.121.1.40#72#e1NIQTI1Nn1wbVdrV1NCQ0w1MUJma2huNzl4UHVLQktIei8vSDZCK21ZNkc5L2VpZXVNPQ==
uid: 2
entryParentId: 2e40ca0e-6081-4c09-9a0f-2dbc80e524cc
createTimestamp: 20180916051255.335Z
entryCSN: 20180916052301.915000Z#000000#001#000000
sn: 1
modifyTimestamp: 20180916052301.915Z
entryDN: uid=2,dc=example,dc=com
SyncStateValue control :
oid : 1.3.6.1.4.1.4203.1.9.1.2
critical : false
syncStateType : 'MODIFY'
entryUUID : '0xFE 0x63 0xEF 0x53 0x85 0x47 0x4A 0xCC 0x92 0xC7 0x2C 0xE0 0x05
0xC0 0xFC 0x14 '
cookie : '0x72 0x69 0x64 0x3D 0x30 0x30 0x36 0x2C 0x63 0x73 0x6E 0x3D 0x32
0x30 0x31 0x38 0x30 0x39 0x31 0x36 0x30 0x35 0x32 0x33 0x30 0x31 0x2E 0x39 0x31
0x35 0x30 0x30 0x30 0x5A 0x23 0x30 0x30 0x30 0x30 0x30 0x30 0x23 0x30 0x30 0x31
0x23 0x30 0x30 0x30 0x30 0x30 0x30 '
)
[13:23:01] DEBUG [org.apache.directory.ldap.client.api.LdapNetworkConnection] -
MSG_04119_GETTING (2,org.apache.directory.ldap.client.api.future.SearchFuture)
[13:23:01] DEBUG [org.apache.directory.ldap.client.api.LdapNetworkConnection] -
MSG_04128_SEARCH_ENTRY_FOUND (MessageType : SEARCH_RESULT_ENTRY
Message ID : 2
Search Result Entry
Entry
dn[n]: uid=2,dc=example,dc=com
objectClass: top
objectClass: inetOrgPerson
objectClass: person
objectClass: organizationalPerson
entryCSN: 20180916052301.915000Z#000000#001#000000
creatorsName: 0.9.2342.19200300.100.1.1= admin ,2.5.4.11= system
modifyTimestamp: 20180916052301.915Z
entryDN: uid=2,dc=example,dc=com
modifiersName: 0.9.2342.19200300.100.1.1= admin ,2.5.4.11= system
entryUUID: fe63ef53-8547-4acc-92c7-2ce005c0fc14
sn: 1
cn: 1
pwdHistory: 0x32 0x30 0x31 0x38 0x30 0x39 0x31 0x36 0x30 0x35 0x31 0x32 0x35
0x35 0x2E 0x33 ...
createTimestamp: 20180916051255.335Z
userPassword: 0x7B 0x53 0x48 0x41 0x32 0x35 0x36 0x7D 0x6C 0x73 0x72 0x6A 0x58
0x4F 0x69 0x70 ...
uid: 2
entryParentId: 2e40ca0e-6081-4c09-9a0f-2dbc80e524cc
SyncStateValue control :
oid : 1.3.6.1.4.1.4203.1.9.1.2
critical : false
syncStateType : 'MODIFY'
entryUUID : '0xFE 0x63 0xEF 0x53 0x85 0x47 0x4A 0xCC 0x92 0xC7 0x2C 0xE0 0x05
0xC0 0xFC 0x14 '
cookie : '0x72 0x69 0x64 0x3D 0x30 0x30 0x36 0x2C 0x63 0x73 0x6E 0x3D 0x32
0x30 0x31 0x38 0x30 0x39 0x31 0x36 0x30 0x35 0x32 0x33 0x30 0x31 0x2E 0x39 0x31
0x35 0x30 0x30 0x30 0x5A 0x23 0x30 0x30 0x30 0x30 0x30 0x30 0x23 0x30 0x30 0x31
0x23 0x30 0x30 0x30 0x30 0x30 0x30 '
)
[13:23:01] DEBUG [org.apache.directory.server.CONSUMER_LOG] - Response from
192.168.163.1:10389 : MessageType : SEARCH_RESULT_ENTRY
Message ID : 2
Search Result Entry
Entry
dn[n]: uid=2,dc=example,dc=com
objectClass: top
objectClass: inetOrgPerson
objectClass: person
objectClass: organizationalPerson
entryCSN: 20180916052301.915000Z#000000#001#000000
creatorsName: 0.9.2342.19200300.100.1.1= admin ,2.5.4.11= system
modifyTimestamp: 20180916052301.915Z
entryDN: uid=2,dc=example,dc=com
modifiersName: 0.9.2342.19200300.100.1.1= admin ,2.5.4.11= system
entryUUID: fe63ef53-8547-4acc-92c7-2ce005c0fc14
sn: 1
cn: 1
pwdHistory: 0x32 0x30 0x31 0x38 0x30 0x39 0x31 0x36 0x30 0x35 0x31 0x32 0x35
0x35 0x2E 0x33 ...
createTimestamp: 20180916051255.335Z
userPassword: 0x7B 0x53 0x48 0x41 0x32 0x35 0x36 0x7D 0x6C 0x73 0x72 0x6A 0x58
0x4F 0x69 0x70 ...
uid: 2
entryParentId: 2e40ca0e-6081-4c09-9a0f-2dbc80e524cc
SyncStateValue control :
oid : 1.3.6.1.4.1.4203.1.9.1.2
critical : false
syncStateType : 'MODIFY'
entryUUID : '0xFE 0x63 0xEF 0x53 0x85 0x47 0x4A 0xCC 0x92 0xC7 0x2C 0xE0 0x05
0xC0 0xFC 0x14 '
cookie : '0x72 0x69 0x64 0x3D 0x30 0x30 0x36 0x2C 0x63 0x73 0x6E 0x3D 0x32
0x30 0x31 0x38 0x30 0x39 0x31 0x36 0x30 0x35 0x32 0x33 0x30 0x31 0x2E 0x39 0x31
0x35 0x30 0x30 0x30 0x5A 0x23 0x30 0x30 0x30 0x30 0x30 0x30 0x23 0x30 0x30 0x31
0x23 0x30 0x30 0x30 0x30 0x30 0x30 '
[13:23:01] DEBUG [org.apache.directory.server.CONSUMER_LOG] - -------------
starting handleSearchResult ------------
[13:23:01] DEBUG [org.apache.directory.server.CONSUMER_LOG] - assigning the
cookie from sync state value control:
rid=006,csn=20180916052301.915000Z#000000#001#000000
[13:23:01] DEBUG [org.apache.directory.server.CONSUMER_LOG] - state name MODIFY
[13:23:01] DEBUG [org.apache.directory.server.CONSUMER_LOG] - entryUUID =
fe63ef53-8547-4acc-92c7-2ce005c0fc14
[13:23:01] DEBUG [org.apache.directory.server.CONSUMER_LOG] - modifying entry
with dn uid=2,dc=example,dc=com
[13:23:01] WARN
[org.apache.directory.server.core.api.interceptor.context.FilteringOperationContext]
- userPassword
[13:23:01] ERROR [org.apache.directory.server.core.schema.SchemaInterceptor] -
ERR_54 Cannot add a value which is already present : 1
[13:23:01] ERROR [org.apache.directory.server.CONSUMER_LOG] - ERR_54 Cannot add
a value which is already present : 1
org.apache.directory.api.ldap.model.exception.LdapAttributeInUseException:
ERR_54 Cannot add a value which is already present : 1
at
org.apache.directory.server.core.schema.SchemaInterceptor.checkModifyEntry(SchemaInterceptor.java:706)
at
org.apache.directory.server.core.schema.SchemaInterceptor.modify(SchemaInterceptor.java:1187)
at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:508)
at
org.apache.directory.server.core.hash.ConfigurableHashingInterceptor.modify(ConfigurableHashingInterceptor.java:190)
at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:508)
at
org.apache.directory.server.core.exception.ExceptionInterceptor.modify(ExceptionInterceptor.java:261)
at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:508)
at
org.apache.directory.server.core.admin.AdministrativePointInterceptor.modify(AdministrativePointInterceptor.java:1460)
at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:508)
at
org.apache.directory.server.core.authz.DefaultAuthorizationInterceptor.modify(DefaultAuthorizationInterceptor.java:284)
at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:508)
at
org.apache.directory.server.core.authz.AciAuthorizationInterceptor.modify(AciAuthorizationInterceptor.java:840)
at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:508)
at
org.apache.directory.server.core.referral.ReferralInterceptor.modify(ReferralInterceptor.java:320)
at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:508)
at
org.apache.directory.server.core.authn.AuthenticationInterceptor.processStandardModify(AuthenticationInterceptor.java:958)
at
org.apache.directory.server.core.authn.AuthenticationInterceptor.modify(AuthenticationInterceptor.java:944)
at
org.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:508)
at
org.apache.directory.server.core.normalization.NormalizationInterceptor.modify(NormalizationInterceptor.java:258)
at
org.apache.directory.server.core.DefaultOperationManager.modify(DefaultOperationManager.java:1101)
at
org.apache.directory.server.ldap.replication.consumer.ReplicationConsumerImpl.modify(ReplicationConsumerImpl.java:1205)
at
org.apache.directory.server.ldap.replication.consumer.ReplicationConsumerImpl.handleSearchResultEntry(ReplicationConsumerImpl.java:419)
at
org.apache.directory.server.ldap.replication.consumer.ReplicationConsumerImpl.doSyncSearch(ReplicationConsumerImpl.java:775)
at
org.apache.directory.server.ldap.replication.consumer.ReplicationConsumerImpl.startSync(ReplicationConsumerImpl.java:572)
at org.apache.directory.server.ldap.LdapServer$2.run(LdapServer.java:778)
at java.lang.Thread.run(Thread.java:748)
[13:23:01] DEBUG [org.apache.directory.server.CONSUMER_LOG] - -------------
Ending handleSearchResult ------------
--
This message was sent by Atlassian JIRA
(v7.6.3#76005)
