Hello,
I've 389DS 1.4.0.21-1 on Debian/Buster in configuration with one master two consumers and several suffixes. After running
dsconf -D "cn=Directory Manager" -w "$pswd" ldap://localhost backend index reindex cesnet_cz
and completing indexing, err logfile on supplier server start show: ERR - _entryrdn_insert_key - Same DN (dn: nsuniqueid=ffffffff-ffffffff-ffffffff-ffffffff,dc=cesnet,dc=cz) is already in the ,entryrdn file with different ID 10458. Expected ID is 10459.
Complete log of that indexing:
[13/Jan/2021:16:43:10.896747048 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: aci [13/Jan/2021:16:43:10.900306795 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: cesnetemplid [13/Jan/2021:16:43:10.901040884 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: cn [13/Jan/2021:16:43:10.902098982 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: dc [13/Jan/2021:16:43:10.902728398 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: displayname [13/Jan/2021:16:43:10.903230082 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: entityidofidp [13/Jan/2021:16:43:10.903811474 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing entryrdn [13/Jan/2021:16:43:10.906245372 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: entrystatus [13/Jan/2021:16:43:10.906993328 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: entryusn [13/Jan/2021:16:43:10.907625883 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: givenName [13/Jan/2021:16:43:10.909503905 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: iphostnumber [13/Jan/2021:16:43:10.910181673 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: mail [13/Jan/2021:16:43:10.911199555 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: mailAlternateAddress [13/Jan/2021:16:43:10.911712896 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: mailHost [13/Jan/2021:16:43:10.912187870 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: member [13/Jan/2021:16:43:10.912657039 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: memberOf [13/Jan/2021:16:43:10.913311121 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: nsCertSubjectDN [13/Jan/2021:16:43:10.913817035 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: nscpEntryDN [13/Jan/2021:16:43:10.915613931 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: nsds5ReplConflict [13/Jan/2021:16:43:10.916112906 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: nsTombstoneCSN [13/Jan/2021:16:43:10.916587945 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: nsuniqueid [13/Jan/2021:16:43:10.918419748 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: ntUniqueId [13/Jan/2021:16:43:10.918898277 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: ntUserDomainId [13/Jan/2021:16:43:10.919347819 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: numsubordinates [13/Jan/2021:16:43:10.919940562 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: objectclass [13/Jan/2021:16:43:10.921719909 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: owner [13/Jan/2021:16:43:10.922432531 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: parentid [13/Jan/2021:16:43:10.923072797 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: seeAlso [13/Jan/2021:16:43:10.923580070 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: sn [13/Jan/2021:16:43:10.924288238 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: sponsor [13/Jan/2021:16:43:10.924959286 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: taccmd [13/Jan/2021:16:43:10.925596618 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: tacnaspointer [13/Jan/2021:16:43:10.926234600 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: tacprofilepointer [13/Jan/2021:16:43:10.926857374 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: tacuserpointer [13/Jan/2021:16:43:10.927401468 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: targetuniqueid [13/Jan/2021:16:43:10.927833486 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: telephoneNumber [13/Jan/2021:16:43:10.928480717 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: uid [13/Jan/2021:16:43:10.929047096 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: uniquemember [13/Jan/2021:16:43:11.845074815 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexed 1000 entries (9%). [13/Jan/2021:16:43:12.658177768 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexed 2000 entries (19%). [13/Jan/2021:16:43:13.208182425 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexed 3000 entries (28%). [13/Jan/2021:16:43:13.960876293 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexed 4000 entries (38%). [13/Jan/2021:16:43:14.630850682 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexed 5000 entries (47%). [13/Jan/2021:16:43:15.394532510 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexed 6000 entries (57%). [13/Jan/2021:16:43:16.170632542 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexed 7000 entries (66%). [13/Jan/2021:16:43:16.796304684 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexed 8000 entries (76%). [13/Jan/2021:16:43:17.506801263 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexed 9000 entries (86%). [13/Jan/2021:16:43:18.067960870 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexed 10000 entries (95%). [13/Jan/2021:16:43:18.243288780 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Finished indexing. [13/Jan/2021:16:43:19.246780004 +0100] - ERR - _entryrdn_insert_key - Same DN (dn: nsuniqueid=ffffffff-ffffffff-ffffffff-ffffffff,dc=cesnet,dc=cz) is already in the entryrdn file with different ID 10458. Expected ID is 10459. [13/Jan/2021:16:43:19.247170757 +0100] - ERR - index_addordel_entry - database index operation failed BAD 1023, err=9999 Unknown error 9999 [13/Jan/2021:16:43:19.247525937 +0100] - ERR - NSMMReplicationPlugin - _replica_configure_ruv - Failed to create replica ruv tombstone entry (dc=cesnet,dc=cz); LDAP error - 1 [13/Jan/2021:16:43:49.252019156 +0100] - ERR - _entryrdn_insert_key - Same DN (dn: nsuniqueid=ffffffff-ffffffff-ffffffff-ffffffff,dc=cesnet,dc=cz) is already in the entryrdn file with different ID 10458. Expected ID is 10459. [13/Jan/2021:16:43:49.252315849 +0100] - ERR - index_addordel_entry - database index operation failed BAD 1023, err=9999 Unknown error 9999 [13/Jan/2021:16:43:49.252556037 +0100] - ERR - NSMMReplicationPlugin - _replica_configure_ruv - Failed to create replica ruv tombstone entry (dc=cesnet,dc=cz); LDAP error - 1
I tried to do indexes one after one, everyting is working fine untill I try to rebuild index for entryrdn and nsuniqueid. The second one start causing error: [13/Jan/2021:15:25:12.460676505 +0100] - ERR - _entryrdn_insert_key - Same DN (dn: nsuniqueid=ffffffff-ffffffff-ffffffff-ffffffff,dc=cesnet,dc=cz) is already in the entryrdn file with different ID 10454. Expected ID is 10456. [13/Jan/2021:15:25:12.460870191 +0100] - ERR - index_addordel_entry - database index operation failed BAD 1023, err=9999 Unknown error 9999 [13/Jan/2021:15:25:12.461119957 +0100] - ERR - NSMMReplicationPlugin - _replica_configure_ruv - Failed to create replica ruv tombstone entry (dc=cesnet,dc=cz); LDAP error - 1
Only solution I've discovered is to disable replication, reinitializing all suffixes. This is quite painful.:(
How to avoid this error? And how to fix it when it happens? Thanks for any sugestions.
Hi Jan,
This is definitely an older version of the server, I would highly suggest to get onto the latest 1.4.x version that you can. 1.4.0 has not been maintained in a very long time, and is missing important fixes.
As for this error, I have not seen this before. The first thing to try would have been to restart the server which promises to refresh the RUV entry & in-memory. If that does not help maybe try to reindex the entryrdn index once more to see if it helps. If that still does not help, then you can export the database (using the replication data option) and reimport it (this will not break replication or require any further action):
dsconf -D "cn=Directory Manager" -w "$pswd" ldap://localhost backend export cesnet_cz --replication --ldif repl_export.ldif
dsconf -D "cn=Directory Manager" -w "$pswd" ldap://localhost backend import cesnet_cz repl_export.ldif
HTH, Mark
On 1/13/21 11:22 AM, Jan Tomasek wrote:
Hello,
I've 389DS 1.4.0.21-1 on Debian/Buster in configuration with one master two consumers and several suffixes. After running
dsconf -D "cn=Directory Manager" -w "$pswd" ldap://localhost backend index reindex cesnet_cz
and completing indexing, err logfile on supplier server start show: ERR - _entryrdn_insert_key - Same DN (dn: nsuniqueid=ffffffff-ffffffff-ffffffff-ffffffff,dc=cesnet,dc=cz) is already in the ,entryrdn file with different ID 10458. Expected ID is 10459.
Complete log of that indexing:
[13/Jan/2021:16:43:10.896747048 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: aci [13/Jan/2021:16:43:10.900306795 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: cesnetemplid [13/Jan/2021:16:43:10.901040884 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: cn [13/Jan/2021:16:43:10.902098982 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: dc [13/Jan/2021:16:43:10.902728398 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: displayname [13/Jan/2021:16:43:10.903230082 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: entityidofidp [13/Jan/2021:16:43:10.903811474 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing entryrdn [13/Jan/2021:16:43:10.906245372 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: entrystatus [13/Jan/2021:16:43:10.906993328 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: entryusn [13/Jan/2021:16:43:10.907625883 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: givenName [13/Jan/2021:16:43:10.909503905 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: iphostnumber [13/Jan/2021:16:43:10.910181673 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: mail [13/Jan/2021:16:43:10.911199555 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: mailAlternateAddress [13/Jan/2021:16:43:10.911712896 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: mailHost [13/Jan/2021:16:43:10.912187870 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: member [13/Jan/2021:16:43:10.912657039 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: memberOf [13/Jan/2021:16:43:10.913311121 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: nsCertSubjectDN [13/Jan/2021:16:43:10.913817035 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: nscpEntryDN [13/Jan/2021:16:43:10.915613931 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: nsds5ReplConflict [13/Jan/2021:16:43:10.916112906 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: nsTombstoneCSN [13/Jan/2021:16:43:10.916587945 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: nsuniqueid [13/Jan/2021:16:43:10.918419748 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: ntUniqueId [13/Jan/2021:16:43:10.918898277 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: ntUserDomainId [13/Jan/2021:16:43:10.919347819 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: numsubordinates [13/Jan/2021:16:43:10.919940562 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: objectclass [13/Jan/2021:16:43:10.921719909 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: owner [13/Jan/2021:16:43:10.922432531 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: parentid [13/Jan/2021:16:43:10.923072797 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: seeAlso [13/Jan/2021:16:43:10.923580070 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: sn [13/Jan/2021:16:43:10.924288238 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: sponsor [13/Jan/2021:16:43:10.924959286 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: taccmd [13/Jan/2021:16:43:10.925596618 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: tacnaspointer [13/Jan/2021:16:43:10.926234600 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: tacprofilepointer [13/Jan/2021:16:43:10.926857374 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: tacuserpointer [13/Jan/2021:16:43:10.927401468 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: targetuniqueid [13/Jan/2021:16:43:10.927833486 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: telephoneNumber [13/Jan/2021:16:43:10.928480717 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: uid [13/Jan/2021:16:43:10.929047096 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexing attribute: uniquemember [13/Jan/2021:16:43:11.845074815 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexed 1000 entries (9%). [13/Jan/2021:16:43:12.658177768 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexed 2000 entries (19%). [13/Jan/2021:16:43:13.208182425 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexed 3000 entries (28%). [13/Jan/2021:16:43:13.960876293 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexed 4000 entries (38%). [13/Jan/2021:16:43:14.630850682 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexed 5000 entries (47%). [13/Jan/2021:16:43:15.394532510 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexed 6000 entries (57%). [13/Jan/2021:16:43:16.170632542 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexed 7000 entries (66%). [13/Jan/2021:16:43:16.796304684 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexed 8000 entries (76%). [13/Jan/2021:16:43:17.506801263 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexed 9000 entries (86%). [13/Jan/2021:16:43:18.067960870 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Indexed 10000 entries (95%). [13/Jan/2021:16:43:18.243288780 +0100] - INFO - ldbm_back_ldbm2index - cesnet_cz: Finished indexing. [13/Jan/2021:16:43:19.246780004 +0100] - ERR - _entryrdn_insert_key - Same DN (dn: nsuniqueid=ffffffff-ffffffff-ffffffff-ffffffff,dc=cesnet,dc=cz) is already in the entryrdn file with different ID 10458. Expected ID is 10459. [13/Jan/2021:16:43:19.247170757 +0100] - ERR - index_addordel_entry - database index operation failed BAD 1023, err=9999 Unknown error 9999 [13/Jan/2021:16:43:19.247525937 +0100] - ERR - NSMMReplicationPlugin - _replica_configure_ruv - Failed to create replica ruv tombstone entry (dc=cesnet,dc=cz); LDAP error - 1 [13/Jan/2021:16:43:49.252019156 +0100] - ERR - _entryrdn_insert_key - Same DN (dn: nsuniqueid=ffffffff-ffffffff-ffffffff-ffffffff,dc=cesnet,dc=cz) is already in the entryrdn file with different ID 10458. Expected ID is 10459. [13/Jan/2021:16:43:49.252315849 +0100] - ERR - index_addordel_entry - database index operation failed BAD 1023, err=9999 Unknown error 9999 [13/Jan/2021:16:43:49.252556037 +0100] - ERR - NSMMReplicationPlugin - _replica_configure_ruv - Failed to create replica ruv tombstone entry (dc=cesnet,dc=cz); LDAP error - 1
I tried to do indexes one after one, everyting is working fine untill I try to rebuild index for entryrdn and nsuniqueid. The second one start causing error: [13/Jan/2021:15:25:12.460676505 +0100] - ERR - _entryrdn_insert_key - Same DN (dn: nsuniqueid=ffffffff-ffffffff-ffffffff-ffffffff,dc=cesnet,dc=cz) is already in the entryrdn file with different ID 10454. Expected ID is 10456. [13/Jan/2021:15:25:12.460870191 +0100] - ERR - index_addordel_entry - database index operation failed BAD 1023, err=9999 Unknown error 9999 [13/Jan/2021:15:25:12.461119957 +0100] - ERR - NSMMReplicationPlugin - _replica_configure_ruv - Failed to create replica ruv tombstone entry (dc=cesnet,dc=cz); LDAP error - 1
Only solution I've discovered is to disable replication, reinitializing all suffixes. This is quite painful.:(
How to avoid this error? And how to fix it when it happens? Thanks for any sugestions.
Hi Mark, On 13. 01. 21 17:33, Mark Reynolds wrote:
This is definitely an older version of the server, I would highly suggest to get onto the latest 1.4.x version that you can. 1.4.0 has not been maintained in a very long time, and is missing important fixes.
I've upgraded to version 1.4.4.9 which is present in upcomming Debian stable (Bullseye). The result is even worse.
After reindexing:
[14/Jan/2021:16:47:17.866756854 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: aci [14/Jan/2021:16:47:17.867468013 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: cn [14/Jan/2021:16:47:17.868599567 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing entryrdn [14/Jan/2021:16:47:17.869738128 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: entryusn [14/Jan/2021:16:47:17.870441180 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: givenName [14/Jan/2021:16:47:17.873042341 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: mail [14/Jan/2021:16:47:17.874059327 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: mailAlternateAddress [14/Jan/2021:16:47:17.874611626 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: mailHost [14/Jan/2021:16:47:17.875048657 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: member [14/Jan/2021:16:47:17.875445102 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: memberOf [14/Jan/2021:16:47:17.876030086 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: nsCertSubjectDN [14/Jan/2021:16:47:17.876506348 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: nscpEntryDN [14/Jan/2021:16:47:17.877063072 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: nsds5ReplConflict [14/Jan/2021:16:47:17.877463986 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: nsTombstoneCSN [14/Jan/2021:16:47:17.877869952 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: nsuniqueid [14/Jan/2021:16:47:17.878523994 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: ntUniqueId [14/Jan/2021:16:47:17.878950068 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: ntUserDomainId [14/Jan/2021:16:47:17.879324003 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: numsubordinates [14/Jan/2021:16:47:17.879937054 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: objectclass [14/Jan/2021:16:47:17.880652956 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: owner [14/Jan/2021:16:47:17.881298947 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: parentid [14/Jan/2021:16:47:17.881917015 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: seeAlso [14/Jan/2021:16:47:17.882350399 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: sn [14/Jan/2021:16:47:17.883025762 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: targetuniqueid [14/Jan/2021:16:47:17.883440145 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: telephoneNumber [14/Jan/2021:16:47:17.884134175 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: uid [14/Jan/2021:16:47:17.884760406 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: uniquemember [14/Jan/2021:16:47:18.749491112 +0100] - INFO - bdb_db2index - cesnet_cz: Indexed 1000 entries (9%). [14/Jan/2021:16:47:19.528484588 +0100] - INFO - bdb_db2index - cesnet_cz: Indexed 2000 entries (19%). [14/Jan/2021:16:47:20.040531342 +0100] - INFO - bdb_db2index - cesnet_cz: Indexed 3000 entries (28%). [14/Jan/2021:16:47:20.769555937 +0100] - INFO - bdb_db2index - cesnet_cz: Indexed 4000 entries (38%). [14/Jan/2021:16:47:21.403762300 +0100] - INFO - bdb_db2index - cesnet_cz: Indexed 5000 entries (47%). [14/Jan/2021:16:47:22.134055315 +0100] - INFO - bdb_db2index - cesnet_cz: Indexed 6000 entries (57%). [14/Jan/2021:16:47:22.861718595 +0100] - INFO - bdb_db2index - cesnet_cz: Indexed 7000 entries (66%). [14/Jan/2021:16:47:23.455932352 +0100] - INFO - bdb_db2index - cesnet_cz: Indexed 8000 entries (76%). [14/Jan/2021:16:47:24.105353501 +0100] - INFO - bdb_db2index - cesnet_cz: Indexed 9000 entries (86%). [14/Jan/2021:16:47:24.603336412 +0100] - INFO - bdb_db2index - cesnet_cz: Indexed 10000 entries (95%). [14/Jan/2021:16:47:24.749496424 +0100] - INFO - bdb_db2index - cesnet_cz: Finished indexing. [14/Jan/2021:16:47:47.778127870 +0100] - ERR - _entryrdn_insert_key - Same DN (dn: nsuniqueid=ffffffff-ffffffff-ffffffff-ffffffff,dc=cesnet,dc=cz) is already in the entryrdn file with different ID 10454. Expected ID is 10456. [14/Jan/2021:16:47:47.778321609 +0100] - ERR - index_addordel_entry - database index operation failed BAD 1023, err=9999 Unknown error 9999 [14/Jan/2021:16:47:47.800034893 +0100] - ERR - NSMMReplicationPlugin - _replica_configure_ruv - Failed to create replica ruv tombstone entry (dc=cesnet,dc=cz); LDAP error - 1
After restarting, server crashes:
Jan 14 16:55:44 cml3 ns-slapd[18736]: [14/Jan/2021:16:55:44.123358593 +0100] - ERR - _entryrdn_insert_key - Same DN (dn: nsuniqueid=ffffffff-ffffffff-ffffffff-ffffffff,dc=cesnet,dc=cz) is already in the entryrdn file with different ID 10454. Expected ID is 10456. Jan 14 16:55:44 cml3 ns-slapd[18736]: [14/Jan/2021:16:55:44.123685788 +0100] - ERR - index_addordel_entry - database index operation failed BAD 1023, err=9999 Unknown error 9999 Jan 14 16:55:44 cml3 ns-slapd[18736]: [14/Jan/2021:16:55:44.145147700 +0100] - ERR - NSMMReplicationPlugin - _replica_configure_ruv - Failed to create replica ruv tombstone entry (dc=cesnet,dc=cz); LDAP error - 1 Jan 14 16:55:44 cml3 ns-slapd[18736]: [14/Jan/2021:16:55:44.145445953 +0100] - ERR - NSMMReplicationPlugin - replica_new - Unable to configure replica dc=cesnet,dc=cz: Jan 14 16:55:44 cml3 kernel: ns-slapd[18736]: segfault at 110 ip 00007fc7b6210a20 sp 00007ffea3966728 error 4 Jan 14 16:55:44 cml3 kernel: Code: 1f 84 00 00 00 00 00 0f 1f 00 48 8b 7f 70 e9 d7 09 fd ff 0f 1f 80 00 00 00 00 48 8b 7f 70 e9 97 1b fd ff 0f 1f 80 00 00 00 00 <48> 8b 87 10 01 00 00 c3 0f 1f 84 00 00 00 00 00 41 54 45 31 e4 55 Jan 14 16:55:44 cml3 systemd[1]: dirsrv@cml3.service: Main process exited, code=killed, status=11/SEGV
and is unable to start.
Solution is to run:
dsctl cml3 db2index cesnet_cz
where I didn't discovered how to index only cesnet_cz backend, it reindexs all. And causes replication stop working:
[15/Jan/2021:09:35:14.406675323 +0100] - ERR - agmt="cn=cesnet_cz-ldap31" (ldap31:636) - clcache_load_buffer - Can't locate CSN 60014363000000010000 in the changelog (DB rc=-30988). If replication stops, the consumer may need to be reinitialized. [15/Jan/2021:09:35:14.406966190 +0100] - ERR - NSMMReplicationPlugin - changelog program - repl_plugin_name_cl - agmt="cn=cesnet_cz-ldap31" (ldap31:636): CSN 60014363000000010000 not found, we aren't as up to date, or we purged [15/Jan/2021:09:35:14.407091288 +0100] - ERR - NSMMReplicationPlugin - send_updates - agmt="cn=cesnet_cz-ldap31" (ldap31:636): Data required to update replica has been purged from the changelog. If the error persists the replica must be reinitialized.
So reinitialize all suffixes on all replicas. And server is again working.
At this moment are replicas 1.4.0 upgraded is only master.
I will try to reduce amount of data in suffix and try elaborate if this problem is causing some our data. :(
Hi Jan,
Would you be able to run those commands:
dbscan -f /var/lib/dirsrv/<instance>/db/cesnet_cz /nsuniqueid.db -k =fffffff-fffffff-fffffff-fffffff -r =fffffff-fffffff-fffffff-fffffff
then for each ID dbscan -f /var/lib/dirsrv/<instance>/db/cesnet_cz /id2entry.db -K <ID>
thanks it could help to diagnose. regards thierry
On 1/15/21 9:56 AM, Jan Tomasek wrote:
Hi Mark, On 13. 01. 21 17:33, Mark Reynolds wrote:
This is definitely an older version of the server, I would highly suggest to get onto the latest 1.4.x version that you can. 1.4.0 has not been maintained in a very long time, and is missing important fixes.
I've upgraded to version 1.4.4.9 which is present in upcomming Debian stable (Bullseye). The result is even worse.
After reindexing:
[14/Jan/2021:16:47:17.866756854 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: aci [14/Jan/2021:16:47:17.867468013 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: cn [14/Jan/2021:16:47:17.868599567 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing entryrdn [14/Jan/2021:16:47:17.869738128 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: entryusn [14/Jan/2021:16:47:17.870441180 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: givenName [14/Jan/2021:16:47:17.873042341 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: mail [14/Jan/2021:16:47:17.874059327 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: mailAlternateAddress [14/Jan/2021:16:47:17.874611626 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: mailHost [14/Jan/2021:16:47:17.875048657 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: member [14/Jan/2021:16:47:17.875445102 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: memberOf [14/Jan/2021:16:47:17.876030086 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: nsCertSubjectDN [14/Jan/2021:16:47:17.876506348 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: nscpEntryDN [14/Jan/2021:16:47:17.877063072 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: nsds5ReplConflict [14/Jan/2021:16:47:17.877463986 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: nsTombstoneCSN [14/Jan/2021:16:47:17.877869952 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: nsuniqueid [14/Jan/2021:16:47:17.878523994 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: ntUniqueId [14/Jan/2021:16:47:17.878950068 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: ntUserDomainId [14/Jan/2021:16:47:17.879324003 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: numsubordinates [14/Jan/2021:16:47:17.879937054 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: objectclass [14/Jan/2021:16:47:17.880652956 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: owner [14/Jan/2021:16:47:17.881298947 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: parentid [14/Jan/2021:16:47:17.881917015 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: seeAlso [14/Jan/2021:16:47:17.882350399 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: sn [14/Jan/2021:16:47:17.883025762 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: targetuniqueid [14/Jan/2021:16:47:17.883440145 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: telephoneNumber [14/Jan/2021:16:47:17.884134175 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: uid [14/Jan/2021:16:47:17.884760406 +0100] - INFO - bdb_db2index - cesnet_cz: Indexing attribute: uniquemember [14/Jan/2021:16:47:18.749491112 +0100] - INFO - bdb_db2index - cesnet_cz: Indexed 1000 entries (9%). [14/Jan/2021:16:47:19.528484588 +0100] - INFO - bdb_db2index - cesnet_cz: Indexed 2000 entries (19%). [14/Jan/2021:16:47:20.040531342 +0100] - INFO - bdb_db2index - cesnet_cz: Indexed 3000 entries (28%). [14/Jan/2021:16:47:20.769555937 +0100] - INFO - bdb_db2index - cesnet_cz: Indexed 4000 entries (38%). [14/Jan/2021:16:47:21.403762300 +0100] - INFO - bdb_db2index - cesnet_cz: Indexed 5000 entries (47%). [14/Jan/2021:16:47:22.134055315 +0100] - INFO - bdb_db2index - cesnet_cz: Indexed 6000 entries (57%). [14/Jan/2021:16:47:22.861718595 +0100] - INFO - bdb_db2index - cesnet_cz: Indexed 7000 entries (66%). [14/Jan/2021:16:47:23.455932352 +0100] - INFO - bdb_db2index - cesnet_cz: Indexed 8000 entries (76%). [14/Jan/2021:16:47:24.105353501 +0100] - INFO - bdb_db2index - cesnet_cz: Indexed 9000 entries (86%). [14/Jan/2021:16:47:24.603336412 +0100] - INFO - bdb_db2index - cesnet_cz: Indexed 10000 entries (95%). [14/Jan/2021:16:47:24.749496424 +0100] - INFO - bdb_db2index - cesnet_cz: Finished indexing. [14/Jan/2021:16:47:47.778127870 +0100] - ERR - _entryrdn_insert_key - Same DN (dn: nsuniqueid=ffffffff-ffffffff-ffffffff-ffffffff,dc=cesnet,dc=cz) is already in the entryrdn file with different ID 10454. Expected ID is 10456. [14/Jan/2021:16:47:47.778321609 +0100] - ERR - index_addordel_entry - database index operation failed BAD 1023, err=9999 Unknown error 9999 [14/Jan/2021:16:47:47.800034893 +0100] - ERR - NSMMReplicationPlugin - _replica_configure_ruv - Failed to create replica ruv tombstone entry (dc=cesnet,dc=cz); LDAP error - 1
After restarting, server crashes:
Jan 14 16:55:44 cml3 ns-slapd[18736]: [14/Jan/2021:16:55:44.123358593 +0100] - ERR - _entryrdn_insert_key - Same DN (dn: nsuniqueid=ffffffff-ffffffff-ffffffff-ffffffff,dc=cesnet,dc=cz) is already in the entryrdn file with different ID 10454. Expected ID is 10456. Jan 14 16:55:44 cml3 ns-slapd[18736]: [14/Jan/2021:16:55:44.123685788 +0100] - ERR - index_addordel_entry - database index operation failed BAD 1023, err=9999 Unknown error 9999 Jan 14 16:55:44 cml3 ns-slapd[18736]: [14/Jan/2021:16:55:44.145147700 +0100] - ERR - NSMMReplicationPlugin - _replica_configure_ruv - Failed to create replica ruv tombstone entry (dc=cesnet,dc=cz); LDAP error - 1 Jan 14 16:55:44 cml3 ns-slapd[18736]: [14/Jan/2021:16:55:44.145445953 +0100] - ERR - NSMMReplicationPlugin - replica_new - Unable to configure replica dc=cesnet,dc=cz: Jan 14 16:55:44 cml3 kernel: ns-slapd[18736]: segfault at 110 ip 00007fc7b6210a20 sp 00007ffea3966728 error 4 Jan 14 16:55:44 cml3 kernel: Code: 1f 84 00 00 00 00 00 0f 1f 00 48 8b 7f 70 e9 d7 09 fd ff 0f 1f 80 00 00 00 00 48 8b 7f 70 e9 97 1b fd ff 0f 1f 80 00 00 00 00 <48> 8b 87 10 01 00 00 c3 0f 1f 84 00 00 00 00 00 41 54 45 31 e4 55 Jan 14 16:55:44 cml3 systemd[1]: dirsrv@cml3.service: Main process exited, code=killed, status=11/SEGV
and is unable to start.
Solution is to run:
dsctl cml3 db2index cesnet_cz
where I didn't discovered how to index only cesnet_cz backend, it reindexs all. And causes replication stop working:
[15/Jan/2021:09:35:14.406675323 +0100] - ERR - agmt="cn=cesnet_cz-ldap31" (ldap31:636) - clcache_load_buffer - Can't locate CSN 60014363000000010000 in the changelog (DB rc=-30988). If replication stops, the consumer may need to be reinitialized. [15/Jan/2021:09:35:14.406966190 +0100] - ERR - NSMMReplicationPlugin - changelog program - repl_plugin_name_cl - agmt="cn=cesnet_cz-ldap31" (ldap31:636): CSN 60014363000000010000 not found, we aren't as up to date, or we purged [15/Jan/2021:09:35:14.407091288 +0100] - ERR - NSMMReplicationPlugin - send_updates - agmt="cn=cesnet_cz-ldap31" (ldap31:636): Data required to update replica has been purged from the changelog. If the error persists the replica must be reinitialized.
So reinitialize all suffixes on all replicas. And server is again working.
At this moment are replicas 1.4.0 upgraded is only master.
I will try to reduce amount of data in suffix and try elaborate if this problem is causing some our data. :(
389-users mailing list -- 389-users@lists.fedoraproject.org To unsubscribe send an email to 389-users-leave@lists.fedoraproject.org Fedora Code of Conduct: https://docs.fedoraproject.org/en-US/project/code-of-conduct/ List Guidelines: https://fedoraproject.org/wiki/Mailing_list_guidelines List Archives: https://lists.fedoraproject.org/archives/list/389-users@lists.fedoraproject....
Hi Thierry,
On 15. 01. 21 11:06, thierry bordaz wrote:
Would you be able to run those commands:
dbscan -f /var/lib/dirsrv/<instance>/db/cesnet_cz /nsuniqueid.db -k =fffffff-fffffff-fffffff-fffffff -r =fffffff-fffffff-fffffff-fffffff
This seqfaults:
root@cml3:~# dbscan -f /var/lib/dirsrv/slapd-cml3/db/test/nsuniqueid.db -k =fffffff-fffffff-fffffff-fffffff -r =fffffff-fffffff-fffffff-fffffff Can't find key '=fffffff-fffffff-fffffff-fffffff' Segmentation fault
strace:
openat(AT_FDCWD, "/var/lib/dirsrv/slapd-cml3/db/test/nsuniqueid.db", O_RDONLY) = 3 fcntl(3, F_GETFD) = 0 fcntl(3, F_SETFD, FD_CLOEXEC) = 0 fstat(3, {st_mode=S_IFREG|0600, st_size=16384, ...}) = 0 mmap(NULL, 16384, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7f51149b3000 fstat(1, {st_mode=S_IFCHR|0600, st_rdev=makedev(0x88, 0x1), ...}) = 0 write(1, "Can't find key '=fffffff-fffffff"..., 50Can't find key '=fffffff-fffffff-fffffff-fffffff' ) = 50 --- SIGSEGV {si_signo=SIGSEGV, si_code=SEGV_MAPERR, si_addr=0x7fff3c000000} --- +++ killed by SIGSEGV +++ Segmentation fault
I've created simple test suffix (see ldif) and problem persist :(
Error is now: [18/Jan/2021:15:36:07.639103043 +0100] - ERR - _entryrdn_insert_key - Same DN (dn: nsuniqueid=ffffffff-ffffffff-ffffffff-ffffffff,dc=test) is already in the entryrdn file with different ID 4. Expected ID is 6. [18/Jan/2021:15:36:07.639405490 +0100] - ERR - index_addordel_entry - database index operation failed BAD 1023, err=9999 Unknown error 9999 [18/Jan/2021:15:36:07.794625784 +0100] - ERR - NSMMReplicationPlugin - _replica_configure_ruv - Failed to create replica ruv tombstone entry (dc=test); LDAP error - 1 [18/Jan/2021:15:36:07.794954251 +0100] - ERR - NSMMReplicationPlugin - replica_new - Unable to configure replica dc=test:
root@cml3:~# dbscan -f /var/lib/dirsrv/slapd-cml3/db/test/nsuniqueid.db =d5658282-599911eb-af359663-f13d537d =d5658283-599911eb-af359663-f13d537d =d5658284-599911eb-af359663-f13d537d =d5658285-599911eb-af359663-f13d537d
root@cml3:~# dbscan -f /var/lib/dirsrv/slapd-cml3/db/test/id2entry.db -K 4 id 4 rdn: nsuniqueid=ffffffff-ffffffff-ffffffff-ffffffff objectClass: top objectClass: nsTombstone objectClass: extensibleobject nsUniqueId: ffffffff-ffffffff-ffffffff-ffffffff nsds50ruv: {replicageneration} 60059bd3000000010000 nsds50ruv: {replica 1 ldap://cml3.cesnet.cz:389} 60059bdd000200010000 60059c66 000000010000 dc: test nscpEntryDN: dc=test nsruvReplicaLastModified: {replica 1 ldap://cml3.cesnet.cz:389} 60059c66 nsds5agmtmaxcsn: dc=test;test-ldap31;ldap31.cesnet.cz;636;65535;60059c66000000 010000 nsds5agmtmaxcsn: dc=test;test-ldap32;ldap32.cesnet.cz;636;65535;60059c66000000 010000 root@cml3:~# dbscan -f /var/lib/dirsrv/slapd-cml3/db/test/id2entry.db -K 6 Can't set cursor to returned item: BDB0073 DB_NOTFOUND: No matching key/data pair found free(): invalid pointer Aborted
After I run reindex on backend: # root@cml3:~# dsctl cml3 db2index test
fffffff... entry shows in nsuniqueid.db
root@cml3:~# dbscan -f /var/lib/dirsrv/slapd-cml3/db/test/nsuniqueid.db =d5658282-599911eb-af359663-f13d537d =d5658283-599911eb-af359663-f13d537d =d5658284-599911eb-af359663-f13d537d =d5658285-599911eb-af359663-f13d537d =ffffffff-ffffffff-ffffffff-ffffffff
Now is server able to start. Need reinitialization of both replicas and after reinitialization works. Untill next complete reindex. ;)
I've tested once again with fresh db. record rdn: nsuniqueid=ffffffff-ffffffff-ffffffff-ffffffff appears in nsuniqueid.db after reinitialization of both replicas is completed.
Isn't my problem related to this: https://github.com/389ds/389-ds-base/issues/273 ?
My system is Debian Buster and 389 DS is in version 1.4.4.9 taken from Debian Bullseye. If I can provide some more debug info please let me know.
I hope I can operate servers this without doing reindex on all attributes, but it would be nice if this will be fixed.
Thanks
On 1/18/21 5:04 PM, Jan Tomasek wrote:
Hi Thierry,
On 15. 01. 21 11:06, thierry bordaz wrote:
Would you be able to run those commands:
dbscan -f /var/lib/dirsrv/<instance>/db/cesnet_cz /nsuniqueid.db -k =fffffff-fffffff-fffffff-fffffff -r =fffffff-fffffff-fffffff-fffffff
This seqfaults:
root@cml3:~# dbscan -f /var/lib/dirsrv/slapd-cml3/db/test/nsuniqueid.db -k =fffffff-fffffff-fffffff-fffffff -r =fffffff-fffffff-fffffff-fffffff Can't find key '=fffffff-fffffff-fffffff-fffffff' Segmentation fault
strace:
openat(AT_FDCWD, "/var/lib/dirsrv/slapd-cml3/db/test/nsuniqueid.db", O_RDONLY) = 3 fcntl(3, F_GETFD) = 0 fcntl(3, F_SETFD, FD_CLOEXEC) = 0 fstat(3, {st_mode=S_IFREG|0600, st_size=16384, ...}) = 0 mmap(NULL, 16384, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7f51149b3000 fstat(1, {st_mode=S_IFCHR|0600, st_rdev=makedev(0x88, 0x1), ...}) = 0 write(1, "Can't find key '=fffffff-fffffff"..., 50Can't find key '=fffffff-fffffff-fffffff-fffffff' ) = 50 --- SIGSEGV {si_signo=SIGSEGV, si_code=SEGV_MAPERR, si_addr=0x7fff3c000000} --- +++ killed by SIGSEGV +++ Segmentation fault
My fault the key (-k) was missing some 'f' it should be dbscan -f /var/lib/dirsrv/slapd-cml3/db/test/nsuniqueid.db -k =ffffffff-ffffffff-ffffffff-ffffffff -r
I've created simple test suffix (see ldif) and problem persist :(
Error is now: [18/Jan/2021:15:36:07.639103043 +0100] - ERR - _entryrdn_insert_key - Same DN (dn: nsuniqueid=ffffffff-ffffffff-ffffffff-ffffffff,dc=test) is already in the entryrdn file with different ID 4. Expected ID is 6. [18/Jan/2021:15:36:07.639405490 +0100] - ERR - index_addordel_entry - database index operation failed BAD 1023, err=9999 Unknown error 9999 [18/Jan/2021:15:36:07.794625784 +0100] - ERR - NSMMReplicationPlugin - _replica_configure_ruv - Failed to create replica ruv tombstone entry (dc=test); LDAP error - 1 [18/Jan/2021:15:36:07.794954251 +0100] - ERR - NSMMReplicationPlugin - replica_new - Unable to configure replica dc=test:
I tried that (on master branch) but did not produce this failure during reindex.
root@cml3:~# dbscan -f /var/lib/dirsrv/slapd-cml3/db/test/nsuniqueid.db =d5658282-599911eb-af359663-f13d537d =d5658283-599911eb-af359663-f13d537d =d5658284-599911eb-af359663-f13d537d =d5658285-599911eb-af359663-f13d537d
root@cml3:~# dbscan -f /var/lib/dirsrv/slapd-cml3/db/test/id2entry.db -K 4 id 4 rdn: nsuniqueid=ffffffff-ffffffff-ffffffff-ffffffff objectClass: top objectClass: nsTombstone objectClass: extensibleobject nsUniqueId: ffffffff-ffffffff-ffffffff-ffffffff nsds50ruv: {replicageneration} 60059bd3000000010000 nsds50ruv: {replica 1 ldap://cml3.cesnet.cz:389} 60059bdd000200010000 60059c66 000000010000 dc: test nscpEntryDN: dc=test nsruvReplicaLastModified: {replica 1 ldap://cml3.cesnet.cz:389} 60059c66 nsds5agmtmaxcsn: dc=test;test-ldap31;ldap31.cesnet.cz;636;65535;60059c66000000 010000 nsds5agmtmaxcsn: dc=test;test-ldap32;ldap32.cesnet.cz;636;65535;60059c66000000 010000
The entry (RUV) 'nsuniqueid=ffffffff-ffffffff-ffffffff-ffffffff,dc=test' may take sometime to appear. The time for the replica to flush the in memory RUV on a DB entry.
root@cml3:~# dbscan -f /var/lib/dirsrv/slapd-cml3/db/test/id2entry.db -K 6 Can't set cursor to returned item: BDB0073 DB_NOTFOUND: No matching key/data pair found free(): invalid pointer Aborted
After I run reindex on backend: # root@cml3:~# dsctl cml3 db2index test
fffffff... entry shows in nsuniqueid.db
root@cml3:~# dbscan -f /var/lib/dirsrv/slapd-cml3/db/test/nsuniqueid.db =d5658282-599911eb-af359663-f13d537d =d5658283-599911eb-af359663-f13d537d =d5658284-599911eb-af359663-f13d537d =d5658285-599911eb-af359663-f13d537d =ffffffff-ffffffff-ffffffff-ffffffff
At this step, db2index and restart did not generate the '_entryrdn_insert_key' error message.
Now is server able to start. Need reinitialization of both replicas and after reinitialization works. Untill next complete reindex. ;)
I've tested once again with fresh db. record rdn: nsuniqueid=ffffffff-ffffffff-ffffffff-ffffffff appears in nsuniqueid.db after reinitialization of both replicas is completed.
Yes there is a small delay before it appears
Isn't my problem related to this: https://github.com/389ds/389-ds-base/issues/273 ?
My system is Debian Buster and 389 DS is in version 1.4.4.9 taken from Debian Bullseye. If I can provide some more debug info please let me know.
Having apply the same steps without that bug, I think 1.4.4.9 is likely missing some fixes vs master branch. I do not recall recent (1.4.x) problem around reindex
regards thierry
I hope I can operate servers this without doing reindex on all attributes, but it would be nice if this will be fixed.
Thanks
389-users@lists.fedoraproject.org