KyuWoo Choi created DIRSERVER-2007:
--------------------------------------
Summary: Error while replicating DS on windows
Key: DIRSERVER-2007
URL: https://issues.apache.org/jira/browse/DIRSERVER-2007
Project: Directory ApacheDS
Issue Type: Bug
Components: ldap
Affects Versions: 2.0.0-M17
Environment: Windows Server 2008 R2 Standard.
java 1.7.0_51
Reporter: KyuWoo Choi
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
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
