You are viewing a plain text version of this content. The canonical link for it is here.
Posted to users@directory.apache.org by 최규우 <ap...@paio.co.kr> on 2014/09/26 10:50:54 UTC
apacheDS 2.0 replication error
I have a 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.
[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.
Fwd: apacheDS 2.0 replication error
Posted by 최규우 <ap...@paio.co.kr>.
I have some trouble while I'm 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.
Fwd: apacheDS 2.0 replication error
Posted by 최규우 <ap...@paio.co.kr>.
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.
Re: apacheDS 2.0 replication error
Posted by 최규우 <ap...@paio.co.kr>.
yes. I confirmed it is provider server on windows issue.
after I set replication provider on my mac pc with consumer on windows
works fine.
2014-09-29 15:31 GMT+09:00 최규우 <ap...@paio.co.kr>:
> I'll report it with details. hope It fixed.
>
> thanks kiran.
>
> 2014-09-29 15:28 GMT+09:00 Kiran Ayyagari <ka...@apache.org>:
>
>>
>>
>> On Mon, Sep 29, 2014 at 11:48 AM, 최규우 <ap...@paio.co.kr> wrote:
>>
>>> thanks for reply.
>>>
>>> Wired. I have 2 DS running without problem.
>>> diskspace is enough, and reading, writing tons of ldif file works fine.
>>> That just happens while replicating 2 servers.
>>>
>>> And remember that the log came out of server (PROVIDER_LOG)
>>>
>>> those DS are both on windows server, as service. the startup wrapper
>>> script is made from installer.
>>>
>>> ok, I think windows has a problem with creating a temporary file that is
>> used for storing
>> sorted results.
>>
>> Can you file a bug report? I will investigate.
>>
>> thank you
>>
>>> 2014-09-29 15:09 GMT+09:00 Kiran Ayyagari <ka...@apache.org>:
>>>
>>>>
>>>>
>>>> On Mon, Sep 29, 2014 at 11:32 AM, 최규우 <ap...@paio.co.kr> wrote:
>>>>
>>>>> to Kiran Ayyagari.
>>>>>
>>>>> I found 'Caused by: java.io.IOException: ?????? ??θ? ã?? ?? ?????ϴ?'
>>>>> in the
>>>>> logfile.
>>>>> and It means "the specified path can not be found" sort of.
>>>>>
>>>>> ok, then I suspect you have a wrong path given to the server startup
>>>> script
>>>> can you check if the path given to store to ApacheDS data contains
>>>> spaces?
>>>> how are you starting the server?
>>>>
>>>>> I missed your message due to I was not in the mailing subscription
>>>>> list.
>>>>>
>>>>> ok, CCed to you this time
>>>>
>>>>>
>>>>>
>>>>>
>>>>> 2014-09-26 17:50 GMT+09:00 최규우 <ap...@paio.co.kr>:
>>>>>
>>>>> > I have a 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.
>>>>> >
>>>>> > [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.
>>>>> >
>>>>> >
>>>>> >
>>>>> >
>>>>> >
>>>>> >
>>>>> >
>>>>> >
>>>>>
>>>>>
>>>>> --
>>>>>
>>>>>
>>>>> 최 규 우 KyuWoo Choi, CEO
>>>>> PAIO co.,ltd.
>>>>> www.paio.co.kr
>>>>> apple@paio.co.kr
>>>>> TEL : 070-8621-0707
>>>>> MO : 010-2834-2335
>>>>> FAX : 02-6919-9010
>>>>> SNS : www.facebook.com/paiofarm
>>>>>
>>>>
>>>>
>>>>
>>>> --
>>>> Kiran Ayyagari
>>>> http://keydap.com
>>>>
>>>
>>>
>>>
>>> --
>>>
>>>
>>> 최 규 우 KyuWoo Choi, CEO
>>> PAIO co.,ltd.
>>> www.paio.co.kr
>>> apple@paio.co.kr
>>> TEL : 070-8621-0707
>>> MO : 010-2834-2335
>>> FAX : 02-6919-9010
>>> SNS : www.facebook.com/paiofarm
>>>
>>
>>
>>
>> --
>> Kiran Ayyagari
>> http://keydap.com
>>
>
>
>
> --
>
>
> 최 규 우 KyuWoo Choi, CEO
> PAIO co.,ltd.
> www.paio.co.kr
> apple@paio.co.kr
> TEL : 070-8621-0707
> MO : 010-2834-2335
> FAX : 02-6919-9010
> SNS : www.facebook.com/paiofarm
>
--
최 규 우 KyuWoo Choi, CEO
PAIO co.,ltd.
www.paio.co.kr
apple@paio.co.kr
TEL : 070-8621-0707
MO : 010-2834-2335
FAX : 02-6919-9010
SNS : www.facebook.com/paiofarm
Re: apacheDS 2.0 replication error
Posted by Kiran Ayyagari <ka...@apache.org>.
On Mon, Sep 29, 2014 at 11:48 AM, 최규우 <ap...@paio.co.kr> wrote:
> thanks for reply.
>
> Wired. I have 2 DS running without problem.
> diskspace is enough, and reading, writing tons of ldif file works fine.
> That just happens while replicating 2 servers.
>
> And remember that the log came out of server (PROVIDER_LOG)
>
> those DS are both on windows server, as service. the startup wrapper
> script is made from installer.
>
> ok, I think windows has a problem with creating a temporary file that is
used for storing
sorted results.
Can you file a bug report? I will investigate.
thank you
> 2014-09-29 15:09 GMT+09:00 Kiran Ayyagari <ka...@apache.org>:
>
>>
>>
>> On Mon, Sep 29, 2014 at 11:32 AM, 최규우 <ap...@paio.co.kr> wrote:
>>
>>> to Kiran Ayyagari.
>>>
>>> I found 'Caused by: java.io.IOException: ?????? ??θ? ã?? ?? ?????ϴ?' in
>>> the
>>> logfile.
>>> and It means "the specified path can not be found" sort of.
>>>
>>> ok, then I suspect you have a wrong path given to the server startup
>> script
>> can you check if the path given to store to ApacheDS data contains spaces?
>> how are you starting the server?
>>
>>> I missed your message due to I was not in the mailing subscription list.
>>>
>>> ok, CCed to you this time
>>
>>>
>>>
>>>
>>> 2014-09-26 17:50 GMT+09:00 최규우 <ap...@paio.co.kr>:
>>>
>>> > I have a 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.
>>> >
>>> > [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.
>>> >
>>> >
>>> >
>>> >
>>> >
>>> >
>>> >
>>> >
>>>
>>>
>>> --
>>>
>>>
>>> 최 규 우 KyuWoo Choi, CEO
>>> PAIO co.,ltd.
>>> www.paio.co.kr
>>> apple@paio.co.kr
>>> TEL : 070-8621-0707
>>> MO : 010-2834-2335
>>> FAX : 02-6919-9010
>>> SNS : www.facebook.com/paiofarm
>>>
>>
>>
>>
>> --
>> Kiran Ayyagari
>> http://keydap.com
>>
>
>
>
> --
>
>
> 최 규 우 KyuWoo Choi, CEO
> PAIO co.,ltd.
> www.paio.co.kr
> apple@paio.co.kr
> TEL : 070-8621-0707
> MO : 010-2834-2335
> FAX : 02-6919-9010
> SNS : www.facebook.com/paiofarm
>
--
Kiran Ayyagari
http://keydap.com
Re: apacheDS 2.0 replication error
Posted by Kiran Ayyagari <ka...@apache.org>.
On Mon, Sep 29, 2014 at 11:32 AM, 최규우 <ap...@paio.co.kr> wrote:
> to Kiran Ayyagari.
>
> I found 'Caused by: java.io.IOException: ?????? ??θ? ã?? ?? ?????ϴ?' in the
> logfile.
> and It means "the specified path can not be found" sort of.
>
> ok, then I suspect you have a wrong path given to the server startup script
can you check if the path given to store to ApacheDS data contains spaces?
how are you starting the server?
> I missed your message due to I was not in the mailing subscription list.
>
> ok, CCed to you this time
>
>
>
> 2014-09-26 17:50 GMT+09:00 최규우 <ap...@paio.co.kr>:
>
> > I have a 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.
> >
> > [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.
> >
> >
> >
> >
> >
> >
> >
> >
>
>
> --
>
>
> 최 규 우 KyuWoo Choi, CEO
> PAIO co.,ltd.
> www.paio.co.kr
> apple@paio.co.kr
> TEL : 070-8621-0707
> MO : 010-2834-2335
> FAX : 02-6919-9010
> SNS : www.facebook.com/paiofarm
>
--
Kiran Ayyagari
http://keydap.com
Re: apacheDS 2.0 replication error
Posted by 최규우 <ap...@paio.co.kr>.
to Kiran Ayyagari.
I found 'Caused by: java.io.IOException: ?????? ??θ? ã?? ?? ?????ϴ?' in the
logfile.
and It means "the specified path can not be found" sort of.
I missed your message due to I was not in the mailing subscription list.
2014-09-26 17:50 GMT+09:00 최규우 <ap...@paio.co.kr>:
> I have a 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.
>
> [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.
>
>
>
>
>
>
>
>
--
최 규 우 KyuWoo Choi, CEO
PAIO co.,ltd.
www.paio.co.kr
apple@paio.co.kr
TEL : 070-8621-0707
MO : 010-2834-2335
FAX : 02-6919-9010
SNS : www.facebook.com/paiofarm
Re: apacheDS 2.0 replication error
Posted by Kiran Ayyagari <ka...@apache.org>.
On Fri, Sep 26, 2014 at 2:20 PM, 최규우 <ap...@paio.co.kr> wrote:
> I have a 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.
>
> [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: ?????? ??θ? ã?? ?? ?????ϴ?
>
> I am assuming that the the reason for exception seems to be printing in
locale specific language,
can you transalte the message and send to us?
> 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.
>
--
Kiran Ayyagari
http://keydap.com