Uploaded image for project: 'Directory ApacheDS'
  1. Directory ApacheDS
  2. DIRSERVER-2007

Error while replicating DS on windows

    XMLWordPrintableJSON

Details

    • Bug
    • Status: Open
    • Major
    • Resolution: Unresolved
    • 2.0.0-M17
    • None
    • replication
    • None
    • Windows Server 2008 R2 Standard.
      java 1.7.0_51

    Description

      I have some trouble while I try to make an replication.

      I set 2 ds, one for master, on for replication.

      ----------------------------------------------------------------------------------------------------------------
      for master I set below

      attr ads-replreqhandler=org.apache.directory.server.ldap.replication.provider.SyncReplRequestHandler
      in org.apache.directory.server.ldap.replication.provider.SyncReplRequestHandler

      ----------------------------------------------------------------------------------------------------------------
      for replication (host & password are replaced *)

      dn: ads-replConsumerId=consumer1,ou=replConsumers,ads-serverId=ldapServer,ou
      =servers,ads-directoryServiceId=default,ou=config
      objectclass: top
      objectclass: ads-base
      objectclass: ads-replConsumer
      ads-replaliasderefmode: never
      ads-replattributes: *
      ads-replconsumerid: consumer1
      ads-replprovhostname: ********
      ads-replprovport: 10389
      ads-replrefreshinterval: 60000
      ads-replrefreshnpersist: TRUE
      ads-replsearchfilter: (objectClass=*)
      ads-replsearchscope: sub
      ads-replsearchsizelimit: 0
      ads-replsearchtimeout: 0
      ads-repluserdn: uid=admin,ou=system
      ads-repluserpassword:: ********
      ads-searchbasedn: dc=example,dc=com
      ads-enabled: TRUE
      ads-replstrictcertvalidation: FALSE
      ads-replusetls: FALSE

      ----------------------------------------------------------------------------------------------------------------
      but It fails replicating on master error log.
      I see IOException while searching job in server.
      please guide me how to solve this problem.

      [17:34:38] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> LookupOperation : FilteringOperationContext for Dn 'uid=admin,ou=system', *

      [17:34:38] DEBUG [org.apache.directory.server.core.authn.AuthenticationInterceptor] - Operation Context: FilteringOperationContext for Dn 'uid=admin,ou=system', *

      [17:34:38] DEBUG [org.apache.directory.server.OPERATION_LOG] - << LookupOperation successful

      [17:34:38] DEBUG [org.apache.directory.server.OPERATION_TIME] - Lookup operation took 285006 ns

      [17:34:38] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> BindOperation : BindContext for Dn 'uid=admin,ou=system', credentials <0x73 0x65 0x63 0x72 0x65 0x74 >

      [17:34:38] DEBUG [org.apache.directory.server.core.authn.AuthenticationInterceptor] - Operation Context: BindContext for Dn 'uid=admin,ou=system', credentials <0x73 0x65 0x63 0x72 0x65 0x74 >

      [17:34:38] DEBUG [org.apache.directory.server.core.authn.AbstractAuthenticator] - Authenticating uid=admin,ou=system

      [17:34:38] DEBUG [org.apache.directory.server.core.authn.AbstractAuthenticator] - checking if account with the Dn uid=admin,ou=system is locked

      [17:34:38] DEBUG [org.apache.directory.server.core.authn.AbstractAuthenticator] - uid=admin,ou=system Authenticated

      [17:34:38] DEBUG [org.apache.directory.server.OPERATION_LOG] - << BindOperation successful

      [17:34:38] DEBUG [org.apache.directory.server.OPERATION_TIME] - Bind operation took 463063 ns

      [17:34:38] DEBUG [org.apache.directory.api.CODEC_LOG] - Encoded message

      MessageType : BIND_RESPONSE

      Message ID : 1

      BindResponse

      Ldap Result

      Result code : (SUCCESS) success

      Matched Dn : 'null'

      Diagnostic message : 'null'

      : 0x30 0x0C 0x02 0x01 0x01 0x61 0x07 0x0A 0x01 0x00 0x04 0x00 0x04 0x00

      [17:34:38] DEBUG [org.apache.directory.server.ldap.handlers.LdapResponseHandler] - Message Sent : MessageType : BIND_RESPONSE

      Message ID : 1

      BindResponse

      Ldap Result

      Result code : (SUCCESS) success

      Matched Dn : 'null'

      Diagnostic message : 'null'

      [17:34:38] DEBUG [org.apache.directory.server.ldap.handlers.request.BindRequestHandler] - Returned SUCCESS message: MessageType : BIND_RESPONSE

      Message ID : 1

      BindResponse

      Ldap Result

      Result code : (SUCCESS) success

      Matched Dn : 'null'

      Diagnostic message : 'null'

      .

      [17:34:38] DEBUG [org.apache.mina.core.filterchain.IoFilterEvent] - Event MESSAGE_RECEIVED has been fired for session 151

      [17:34:38] DEBUG [org.apache.mina.filter.codec.ProtocolCodecFilter] - Processing a MESSAGE_RECEIVED for session 151

      [17:34:38] DEBUG [org.apache.directory.api.CODEC_LOG] - Decoding the PDU :

      [17:34:38] DEBUG [org.apache.directory.api.CODEC_LOG] - 0x30 0x79 0x02 0x01 0x02 0x63 0x34 0x04 0x11 0x64 0x63 0x3D 0x65 0x78 0x61 0x6D 0x70 0x6C 0x65 0x2C 0x64 0x63 0x3D 0x63 0x6F 0x6D 0x0A 0x01 0x02 0x0A 0x01 0x00 0x02 0x01 0x00 0x02 0x01 0x00 0x01 0x01 0x00 0x87 0x0B 0x6F 0x62 0x6A 0x65 0x63 0x74 0x43 0x6C 0x61 0x73 0x73 0x30 0x03 0x04 0x01 0x2A 0xA0 0x3E 0x30 0x21 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 0x31 0x04 0x05 0x30 0x03 0x0A 0x01 0x03 0x30 0x19 0x04 0x17 0x32 0x2E 0x31 0x36 0x2E 0x38 0x34 0x30 0x2E 0x31 0x2E 0x31 0x31 0x33 0x37 0x33 0x30 0x2E 0x33 0x2E 0x34 0x2E 0x32

      [17:34:38] DEBUG [org.apache.directory.api.CODEC_LOG] - Decoded LdapMessage : MessageType : SEARCH_REQUEST

      Message ID : 2

      SearchRequest

      baseDn : 'dc=example,dc=com'

      filter : '(objectClass=*)'

      scope : whole subtree

      typesOnly : false

      Size Limit : no limit

      Time Limit : no limit

      Deref Aliases : never Deref Aliases

      attributes : '*'

      org.apache.directory.api.ldap.model.message.SearchRequestImpl@326a523d SyncRequestValue control :

      oid : 1.3.6.1.4.1.4203.1.9.1.1

      critical : false

      mode : 'REFRESH_AND_PERSIST'

      cookie : ''

      reloadHint : 'false'

      ManageDsaITImpl Control

      Type OID : '2.16.840.1.113730.3.4.2'

      Criticality : 'false'

      '

      [17:34:38] DEBUG [org.apache.mina.core.filterchain.IoFilterEvent] - Firing a MESSAGE_RECEIVED event for session 151

      [17:34:38] DEBUG [org.apache.directory.server.ldap.handlers.request.SearchRequestHandler] - Handling single reply request: MessageType : SEARCH_REQUEST

      Message ID : 2

      SearchRequest

      baseDn : 'dc=example,dc=com'

      filter : '(objectClass=*)'

      scope : whole subtree

      typesOnly : false

      Size Limit : no limit

      Time Limit : no limit

      Deref Aliases : never Deref Aliases

      attributes : '*'

      org.apache.directory.api.ldap.model.message.SearchRequestImpl@326a523d SyncRequestValue control :

      oid : 1.3.6.1.4.1.4203.1.9.1.1

      critical : false

      mode : 'REFRESH_AND_PERSIST'

      cookie : ''

      reloadHint : 'false'

      ManageDsaITImpl Control

      Type OID : '2.16.840.1.113730.3.4.2'

      Criticality : 'false'

      '

      [17:34:38] DEBUG [org.apache.directory.server.PROVIDER_LOG] - Received a Syncrepl request : MessageType : SEARCH_REQUEST

      Message ID : 2

      SearchRequest

      baseDn : 'dc=example,dc=com'

      filter : '(objectClass=*)'

      scope : whole subtree

      typesOnly : false

      Size Limit : no limit

      Time Limit : no limit

      Deref Aliases : never Deref Aliases

      attributes : '*'

      org.apache.directory.api.ldap.model.message.SearchRequestImpl@326a523d SyncRequestValue control :

      oid : 1.3.6.1.4.1.4203.1.9.1.1

      critical : false

      mode : 'REFRESH_AND_PERSIST'

      cookie : ''

      reloadHint : 'false'

      ManageDsaITImpl Control

      Type OID : '2.16.840.1.113730.3.4.2'

      Criticality : 'false'

      '

      from LdapSession : <0.9.2342.19200300.100.1.1=admin,2.5.4.11=system,/127.0.0.1:60165>

      [17:34:38] DEBUG [org.apache.directory.server.PROVIDER_LOG] - Received a replication request with no cookie

      [17:34:38] DEBUG [org.apache.directory.server.PROVIDER_LOG] - Starting an initial refresh

      [17:34:38] DEBUG [org.apache.directory.server.PROVIDER_LOG] - Adding sort control to sort the entries by entryDn attribute to preserve order of insertion

      [17:34:38] DEBUG [org.apache.directory.server.PROVIDER_LOG] - creating a new event log for the replica with id 149

      [17:34:38] DEBUG [org.apache.directory.server.PROVIDER_LOG] - Creating the replication queue for replica 149

      [17:34:38] DEBUG [org.apache.directory.server.PROVIDER_LOG] - Starting the replicaLog ReplicaEventLog [hostName=127.0.0.1, searchFilter=(objectClass=*), lastSentCsn=null, searchCriteria=Notification criteria : 'null', '(objectClass=*)', 'one', 'DEREF_ALWAYS', 'ALL EVENTS', replicaId=149, refreshNPersist=true, maxInactivePeriod=-1, purgeThresholdCount=10000, journalFile=C:\Program Files (x86)\ApacheDS\instances\default\syncrepl-data\REPL_EVENT_LOG.149, dirty=false, consumerEntryDn=null]

      [17:34:38] DEBUG [org.apache.directory.server.PROVIDER_LOG] - Simple Search MessageType : SEARCH_REQUEST

      Message ID : 2

      SearchRequest

      baseDn : 'dc=example,dc=com'

      filter : '(objectClass=*)'

      scope : whole subtree

      typesOnly : false

      Size Limit : no limit

      Time Limit : no limit

      Deref Aliases : never Deref Aliases

      attributes : '*', '+'

      org.apache.directory.api.ldap.model.message.SearchRequestImpl@eba4a1f2 SyncRequestValue control :

      oid : 1.3.6.1.4.1.4203.1.9.1.1

      critical : false

      mode : 'REFRESH_AND_PERSIST'

      cookie : ''

      reloadHint : 'false'

      SortRequestControlImpl [sortKeys=[SortKey : [entryDN, 2.5.13.1, reverse]]] ManageDsaITImpl Control

      Type OID : '2.16.840.1.113730.3.4.2'

      Criticality : 'false'

      '

      for LdapSession : <0.9.2342.19200300.100.1.1=admin,2.5.4.11=system,127.0.0.1/127.0.0.1:60165>

      [17:34:38] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> SearchOperation : SearchContext for Dn 'dc=example,dc=com', filter :'(objectClass=*)'

      [17:34:38] DEBUG [org.apache.directory.server.core.authn.AuthenticationInterceptor] - Operation Context: SearchContext for Dn 'dc=example,dc=com', filter :'All'

      [17:34:38] DEBUG [org.apache.directory.server.xdbm.search.impl.DefaultSearchEngine] - Nb results : 9223372036854775807 for filter : (#

      {SUBTREE_SCOPE (Estimated), 'dc=example,dc=com', NEVER_DEREF_ALIASES}

      :[2])

      [17:34:38] DEBUG [org.apache.directory.CURSOR_LOG] - Creating NoDupsCursor org.apache.directory.server.core.partition.impl.btree.jdbm.NoDupsCursor@403bbaac

      [17:34:38] DEBUG [org.apache.directory.CURSOR_LOG] - Creating IndexCursorAdaptor IndexCursorAdaptor (absent) :

      [17:34:38] DEBUG [org.apache.directory.CURSOR_LOG] - Creating SetCursor SetCursor :

      Index : -1

      [17:34:38] DEBUG [org.apache.directory.CURSOR_LOG] - Creating EntryCursorAdaptor EntryCursorAdaptor

      [17:34:38] DEBUG [org.apache.directory.CURSOR_LOG] - Creating BaseEntryFilteringCursor BaseEntryFilteringCursor, no wrapped

      No filter

      No prefetched

      [17:34:38] DEBUG [org.apache.directory.server.OPERATION_LOG] - << SearchOperation successful

      [17:34:38] DEBUG [org.apache.directory.server.OPERATION_TIME] - Search operation took 377731 ns

      [17:34:38] ERROR [org.apache.directory.server.PROVIDER_LOG] - Failed to handle the syncrepl request

      org.apache.directory.api.ldap.model.exception.LdapException: java.io.IOException: ?????? θ? ã ?? ?????ϴ?

      at org.apache.directory.server.core.shared.DefaultCoreSession.search(DefaultCoreSession.java:1157)

      at org.apache.directory.server.ldap.replication.provider.SyncReplRequestHandler.doSimpleSearch(SyncReplRequestHandler.java:648)

      at org.apache.directory.server.ldap.replication.provider.SyncReplRequestHandler.doInitialRefresh(SyncReplRequestHandler.java:562)

      at org.apache.directory.server.ldap.replication.provider.SyncReplRequestHandler.handleSyncRequest(SyncReplRequestHandler.java:311)

      at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleReplication(SearchRequestHandler.java:238)

      at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:198)

      at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:93)

      at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:207)

      at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:56)

      at org.apache.mina.handler.demux.DemuxingIoHandler.messageReceived(DemuxingIoHandler.java:221)

      at org.apache.directory.server.ldap.LdapProtocolHandler.messageReceived(LdapProtocolHandler.java:217)

      at org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:690)

      at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:417)

      at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:47)

      at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:765)

      at org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:74)

      at org.apache.mina.core.session.IoEvent.run(IoEvent.java:63)

      at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.runTask(UnorderedThreadPoolExecutor.java:474)

      at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.run(UnorderedThreadPoolExecutor.java:428)

      at java.lang.Thread.run(Thread.java:744)

      Caused by: java.io.IOException: ?????? θ? ã ?? ?????ϴ?

      at java.io.WinNTFileSystem.createFileExclusively(Native Method)

      at java.io.File.createNewFile(File.java:1006)

      at java.io.File.createTempFile(File.java:1989)

      at java.io.File.createTempFile(File.java:2040)

      at org.apache.directory.server.core.shared.DefaultCoreSession.sortResults(DefaultCoreSession.java:1342)

      at org.apache.directory.server.core.shared.DefaultCoreSession.search(DefaultCoreSession.java:1130)

      ... 19 more

      [17:34:39] WARN [org.apache.directory.server.ldap.LdapProtocolHandler] - Unexpected exception forcing session to close: sending disconnect notice to client.

      org.apache.directory.api.ldap.model.exception.LdapException: java.io.IOException: ?????? θ? ã ?? ?????ϴ?

      at org.apache.directory.server.ldap.replication.provider.SyncReplRequestHandler.handleSyncRequest(SyncReplRequestHandler.java:349)

      at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleReplication(SearchRequestHandler.java:238)

      at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:198)

      at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:93)

      at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:207)

      at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:56)

      at org.apache.mina.handler.demux.DemuxingIoHandler.messageReceived(DemuxingIoHandler.java:221)

      at org.apache.directory.server.ldap.LdapProtocolHandler.messageReceived(LdapProtocolHandler.java:217)

      at org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:690)

      at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:417)

      at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:47)

      at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:765)

      at org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:74)

      at org.apache.mina.core.session.IoEvent.run(IoEvent.java:63)

      at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.runTask(UnorderedThreadPoolExecutor.java:474)

      at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.run(UnorderedThreadPoolExecutor.java:428)

      at java.lang.Thread.run(Thread.java:744)

      Caused by: org.apache.directory.api.ldap.model.exception.LdapException: java.io.IOException: ?????? θ? ã ?? ?????ϴ?

      at org.apache.directory.server.core.shared.DefaultCoreSession.search(DefaultCoreSession.java:1157)

      at org.apache.directory.server.ldap.replication.provider.SyncReplRequestHandler.doSimpleSearch(SyncReplRequestHandler.java:648)

      at org.apache.directory.server.ldap.replication.provider.SyncReplRequestHandler.doInitialRefresh(SyncReplRequestHandler.java:562)

      at org.apache.directory.server.ldap.replication.provider.SyncReplRequestHandler.handleSyncRequest(SyncReplRequestHandler.java:311)

      ... 16 more

      Caused by: java.io.IOException: ?????? θ? ã ?? ?????ϴ?

      at java.io.WinNTFileSystem.createFileExclusively(Native Method)

      at java.io.File.createNewFile(File.java:1006)

      at java.io.File.createTempFile(File.java:1989)

      at java.io.File.createTempFile(File.java:2040)

      at org.apache.directory.server.core.shared.DefaultCoreSession.sortResults(DefaultCoreSession.java:1342)

      at org.apache.directory.server.core.shared.DefaultCoreSession.search(DefaultCoreSession.java:1130)

      ... 19 more

      [17:34:39] DEBUG [org.apache.directory.api.CODEC_LOG] - Encoded message

      MessageType : EXTENDED_RESPONSE

      Message ID : 0

      Extended Response

      ResponseName :'1.3.6.1.4.1.1466.20036'

      Ldap Result

      Result code : (PROTOCOL_ERROR) protocolError

      Matched Dn : 'null'

      Diagnostic message : 'PROTOCOL_ERROR: The server will disconnect!'

      : 0x30 0x4F 0x02 0x01 0x00 0x78 0x4A 0x0A 0x01 0x02 0x04 0x00 0x04 0x2B 0x50 0x52 0x4F 0x54 0x4F 0x43 0x4F 0x4C 0x5F 0x45 0x52 0x52 0x4F 0x52 0x3A 0x20 0x54 0x68 0x65 0x20 0x73 0x65 0x72 0x76 0x65 0x72 0x20 0x77 0x69 0x6C 0x6C 0x20 0x64 0x69 0x73 0x63 0x6F 0x6E 0x6E 0x65 0x63 0x74 0x21 0x8A 0x16 0x31 0x2E 0x33 0x2E 0x36 0x2E 0x31 0x2E 0x34 0x2E 0x31 0x2E 0x31 0x34 0x36 0x36 0x2E 0x32 0x30 0x30 0x33 0x36

      [17:34:39] DEBUG [org.apache.directory.server.ldap.LdapProtocolHandler] - Cleaning the LdapSession : <0.9.2342.19200300.100.1.1=admin,2.5.4.11=system,127.0.0.1/127.0.0.1:60165> session

      [17:34:39] DEBUG [org.apache.mina.core.filterchain.IoFilterEvent] - Event MESSAGE_RECEIVED has been fired for session 151

      [17:34:39] INFO [org.apache.directory.server.ldap.handlers.LdapResponseHandler] - ignoring the message MessageType : EXTENDED_RESPONSE

      Message ID : 0

      Extended Response

      ResponseName :'1.3.6.1.4.1.1466.20036'

      Ldap Result

      Result code : (PROTOCOL_ERROR) protocolError

      Matched Dn : 'null'

      Diagnostic message : 'PROTOCOL_ERROR: The server will disconnect!'

      received from null session

      [17:34:39] WARN [org.apache.directory.server.ldap.LdapProtocolHandler] - Null LdapSession given to cleanUpSession.

      It came out of user mailing list.
      http://mail-archives.apache.org/mod_mbox/directory-users/201409.mbox/browser

      Attachments

        Issue Links

          is related to

          Bug - A problem which impairs or prevents the functions of the product. DIRSERVER-2322 ApacheDS default server instance not starting - Error 1067

          • Blocker - Blocks development and/or testing work, production could not run
          • Resolved

          Activity

            People

              Unassigned Unassigned
              applepaio KyuWoo Choi
              Votes:
              1 Vote for this issue
              Watchers:
              4 Start watching this issue

              Dates

                Created:
                Updated: