You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@directory.apache.org by "Kiran Ayyagari (JIRA)" <ji...@apache.org> on 2014/10/07 11:56:33 UTC
[jira] [Commented] (DIRSERVER-2007) Error while replicating DS on
windows
[ https://issues.apache.org/jira/browse/DIRSERVER-2007?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=14161707#comment-14161707 ]
Kiran Ayyagari commented on DIRSERVER-2007:
-------------------------------------------
This appears to be due to the lengthy name of the temporary file created on Windows 2008 with UUID prefix.
However this is not reproducible on Windows 7.
I have applied a patch which uses a short file name prefix rather than a UUID. See here http://svn.apache.org/r1629843.
Can you test this patch on your windows machine and let us know.?
> Error while replicating DS on windows
> -------------------------------------
>
> Key: DIRSERVER-2007
> URL: https://issues.apache.org/jira/browse/DIRSERVER-2007
> Project: Directory ApacheDS
> Issue Type: Bug
> Components: ldap
> Affects Versions: 2.0.0-M17
> Environment: Windows Server 2008 R2 Standard.
> java 1.7.0_51
> Reporter: KyuWoo Choi
>
> I have some trouble while I try to make an replication.
> I set 2 ds, one for master, on for replication.
> ----------------------------------------------------------------------------------------------------------------
> for master I set below
> attr ads-replreqhandler=org.apache.directory.server.ldap.replication.provider.SyncReplRequestHandler
> in org.apache.directory.server.ldap.replication.provider.SyncReplRequestHandler
> ----------------------------------------------------------------------------------------------------------------
> for replication (host & password are replaced *)
> dn: ads-replConsumerId=consumer1,ou=replConsumers,ads-serverId=ldapServer,ou
> =servers,ads-directoryServiceId=default,ou=config
> objectclass: top
> objectclass: ads-base
> objectclass: ads-replConsumer
> ads-replaliasderefmode: never
> ads-replattributes: *
> ads-replconsumerid: consumer1
> ads-replprovhostname: ********
> ads-replprovport: 10389
> ads-replrefreshinterval: 60000
> ads-replrefreshnpersist: TRUE
> ads-replsearchfilter: (objectClass=*)
> ads-replsearchscope: sub
> ads-replsearchsizelimit: 0
> ads-replsearchtimeout: 0
> ads-repluserdn: uid=admin,ou=system
> ads-repluserpassword:: ********
> ads-searchbasedn: dc=example,dc=com
> ads-enabled: TRUE
> ads-replstrictcertvalidation: FALSE
> ads-replusetls: FALSE
> ----------------------------------------------------------------------------------------------------------------
> but It fails replicating on master error log.
> I see IOException while searching job in server.
> please guide me how to solve this problem.
> [17:34:38] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> LookupOperation : FilteringOperationContext for Dn 'uid=admin,ou=system', *
> [17:34:38] DEBUG [org.apache.directory.server.core.authn.AuthenticationInterceptor] - Operation Context: FilteringOperationContext for Dn 'uid=admin,ou=system', *
> [17:34:38] DEBUG [org.apache.directory.server.OPERATION_LOG] - << LookupOperation successful
> [17:34:38] DEBUG [org.apache.directory.server.OPERATION_TIME] - Lookup operation took 285006 ns
> [17:34:38] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> BindOperation : BindContext for Dn 'uid=admin,ou=system', credentials <0x73 0x65 0x63 0x72 0x65 0x74 >
> [17:34:38] DEBUG [org.apache.directory.server.core.authn.AuthenticationInterceptor] - Operation Context: BindContext for Dn 'uid=admin,ou=system', credentials <0x73 0x65 0x63 0x72 0x65 0x74 >
> [17:34:38] DEBUG [org.apache.directory.server.core.authn.AbstractAuthenticator] - Authenticating uid=admin,ou=system
> [17:34:38] DEBUG [org.apache.directory.server.core.authn.AbstractAuthenticator] - checking if account with the Dn uid=admin,ou=system is locked
> [17:34:38] DEBUG [org.apache.directory.server.core.authn.AbstractAuthenticator] - uid=admin,ou=system Authenticated
> [17:34:38] DEBUG [org.apache.directory.server.OPERATION_LOG] - << BindOperation successful
> [17:34:38] DEBUG [org.apache.directory.server.OPERATION_TIME] - Bind operation took 463063 ns
> [17:34:38] DEBUG [org.apache.directory.api.CODEC_LOG] - Encoded message
> MessageType : BIND_RESPONSE
> Message ID : 1
> BindResponse
> Ldap Result
> Result code : (SUCCESS) success
> Matched Dn : 'null'
> Diagnostic message : 'null'
> : 0x30 0x0C 0x02 0x01 0x01 0x61 0x07 0x0A 0x01 0x00 0x04 0x00 0x04 0x00
> [17:34:38] DEBUG [org.apache.directory.server.ldap.handlers.LdapResponseHandler] - Message Sent : MessageType : BIND_RESPONSE
> Message ID : 1
> BindResponse
> Ldap Result
> Result code : (SUCCESS) success
> Matched Dn : 'null'
> Diagnostic message : 'null'
> [17:34:38] DEBUG [org.apache.directory.server.ldap.handlers.request.BindRequestHandler] - Returned SUCCESS message: MessageType : BIND_RESPONSE
> Message ID : 1
> BindResponse
> Ldap Result
> Result code : (SUCCESS) success
> Matched Dn : 'null'
> Diagnostic message : 'null'
> .
> [17:34:38] DEBUG [org.apache.mina.core.filterchain.IoFilterEvent] - Event MESSAGE_RECEIVED has been fired for session 151
> [17:34:38] DEBUG [org.apache.mina.filter.codec.ProtocolCodecFilter] - Processing a MESSAGE_RECEIVED for session 151
> [17:34:38] DEBUG [org.apache.directory.api.CODEC_LOG] - Decoding the PDU :
> [17:34:38] DEBUG [org.apache.directory.api.CODEC_LOG] - 0x30 0x79 0x02 0x01 0x02 0x63 0x34 0x04 0x11 0x64 0x63 0x3D 0x65 0x78 0x61 0x6D 0x70 0x6C 0x65 0x2C 0x64 0x63 0x3D 0x63 0x6F 0x6D 0x0A 0x01 0x02 0x0A 0x01 0x00 0x02 0x01 0x00 0x02 0x01 0x00 0x01 0x01 0x00 0x87 0x0B 0x6F 0x62 0x6A 0x65 0x63 0x74 0x43 0x6C 0x61 0x73 0x73 0x30 0x03 0x04 0x01 0x2A 0xA0 0x3E 0x30 0x21 0x04 0x18 0x31 0x2E 0x33 0x2E 0x36 0x2E 0x31 0x2E 0x34 0x2E 0x31 0x2E 0x34 0x32 0x30 0x33 0x2E 0x31 0x2E 0x39 0x2E 0x31 0x2E 0x31 0x04 0x05 0x30 0x03 0x0A 0x01 0x03 0x30 0x19 0x04 0x17 0x32 0x2E 0x31 0x36 0x2E 0x38 0x34 0x30 0x2E 0x31 0x2E 0x31 0x31 0x33 0x37 0x33 0x30 0x2E 0x33 0x2E 0x34 0x2E 0x32
> [17:34:38] DEBUG [org.apache.directory.api.CODEC_LOG] - Decoded LdapMessage : MessageType : SEARCH_REQUEST
> Message ID : 2
> SearchRequest
> baseDn : 'dc=example,dc=com'
> filter : '(objectClass=*)'
> scope : whole subtree
> typesOnly : false
> Size Limit : no limit
> Time Limit : no limit
> Deref Aliases : never Deref Aliases
> attributes : '*'
> org.apache.directory.api.ldap.model.message.SearchRequestImpl@326a523d SyncRequestValue control :
> oid : 1.3.6.1.4.1.4203.1.9.1.1
> critical : false
> mode : 'REFRESH_AND_PERSIST'
> cookie : ''
> reloadHint : 'false'
> ManageDsaITImpl Control
> Type OID : '2.16.840.1.113730.3.4.2'
> Criticality : 'false'
> '
> [17:34:38] DEBUG [org.apache.mina.core.filterchain.IoFilterEvent] - Firing a MESSAGE_RECEIVED event for session 151
> [17:34:38] DEBUG [org.apache.directory.server.ldap.handlers.request.SearchRequestHandler] - Handling single reply request: MessageType : SEARCH_REQUEST
> Message ID : 2
> SearchRequest
> baseDn : 'dc=example,dc=com'
> filter : '(objectClass=*)'
> scope : whole subtree
> typesOnly : false
> Size Limit : no limit
> Time Limit : no limit
> Deref Aliases : never Deref Aliases
> attributes : '*'
> org.apache.directory.api.ldap.model.message.SearchRequestImpl@326a523d SyncRequestValue control :
> oid : 1.3.6.1.4.1.4203.1.9.1.1
> critical : false
> mode : 'REFRESH_AND_PERSIST'
> cookie : ''
> reloadHint : 'false'
> ManageDsaITImpl Control
> Type OID : '2.16.840.1.113730.3.4.2'
> Criticality : 'false'
> '
> [17:34:38] DEBUG [org.apache.directory.server.PROVIDER_LOG] - Received a Syncrepl request : MessageType : SEARCH_REQUEST
> Message ID : 2
> SearchRequest
> baseDn : 'dc=example,dc=com'
> filter : '(objectClass=*)'
> scope : whole subtree
> typesOnly : false
> Size Limit : no limit
> Time Limit : no limit
> Deref Aliases : never Deref Aliases
> attributes : '*'
> org.apache.directory.api.ldap.model.message.SearchRequestImpl@326a523d SyncRequestValue control :
> oid : 1.3.6.1.4.1.4203.1.9.1.1
> critical : false
> mode : 'REFRESH_AND_PERSIST'
> cookie : ''
> reloadHint : 'false'
> ManageDsaITImpl Control
> Type OID : '2.16.840.1.113730.3.4.2'
> Criticality : 'false'
> '
> from LdapSession : <0.9.2342.19200300.100.1.1=admin,2.5.4.11=system,/127.0.0.1:60165>
> [17:34:38] DEBUG [org.apache.directory.server.PROVIDER_LOG] - Received a replication request with no cookie
> [17:34:38] DEBUG [org.apache.directory.server.PROVIDER_LOG] - Starting an initial refresh
> [17:34:38] DEBUG [org.apache.directory.server.PROVIDER_LOG] - Adding sort control to sort the entries by entryDn attribute to preserve order of insertion
> [17:34:38] DEBUG [org.apache.directory.server.PROVIDER_LOG] - creating a new event log for the replica with id 149
> [17:34:38] DEBUG [org.apache.directory.server.PROVIDER_LOG] - Creating the replication queue for replica 149
> [17:34:38] DEBUG [org.apache.directory.server.PROVIDER_LOG] - Starting the replicaLog ReplicaEventLog [hostName=127.0.0.1, searchFilter=(objectClass=*), lastSentCsn=null, searchCriteria=Notification criteria : 'null', '(objectClass=*)', 'one', 'DEREF_ALWAYS', 'ALL EVENTS', replicaId=149, refreshNPersist=true, maxInactivePeriod=-1, purgeThresholdCount=10000, journalFile=C:\Program Files (x86)\ApacheDS\instances\default\syncrepl-data\REPL_EVENT_LOG.149, dirty=false, consumerEntryDn=null]
> [17:34:38] DEBUG [org.apache.directory.server.PROVIDER_LOG] - Simple Search MessageType : SEARCH_REQUEST
> Message ID : 2
> SearchRequest
> baseDn : 'dc=example,dc=com'
> filter : '(objectClass=*)'
> scope : whole subtree
> typesOnly : false
> Size Limit : no limit
> Time Limit : no limit
> Deref Aliases : never Deref Aliases
> attributes : '*', '+'
> org.apache.directory.api.ldap.model.message.SearchRequestImpl@eba4a1f2 SyncRequestValue control :
> oid : 1.3.6.1.4.1.4203.1.9.1.1
> critical : false
> mode : 'REFRESH_AND_PERSIST'
> cookie : ''
> reloadHint : 'false'
> SortRequestControlImpl [sortKeys=[SortKey : [entryDN, 2.5.13.1, reverse]]] ManageDsaITImpl Control
> Type OID : '2.16.840.1.113730.3.4.2'
> Criticality : 'false'
> '
> for LdapSession : <0.9.2342.19200300.100.1.1=admin,2.5.4.11=system,127.0.0.1/127.0.0.1:60165>
> [17:34:38] DEBUG [org.apache.directory.server.OPERATION_LOG] - >> SearchOperation : SearchContext for Dn 'dc=example,dc=com', filter :'(objectClass=*)'
> [17:34:38] DEBUG [org.apache.directory.server.core.authn.AuthenticationInterceptor] - Operation Context: SearchContext for Dn 'dc=example,dc=com', filter :'All'
> [17:34:38] DEBUG [org.apache.directory.server.xdbm.search.impl.DefaultSearchEngine] - Nb results : 9223372036854775807 for filter : (#{SUBTREE_SCOPE (Estimated), 'dc=example,dc=com', NEVER_DEREF_ALIASES}:[2])
> [17:34:38] DEBUG [org.apache.directory.CURSOR_LOG] - Creating NoDupsCursor org.apache.directory.server.core.partition.impl.btree.jdbm.NoDupsCursor@403bbaac
> [17:34:38] DEBUG [org.apache.directory.CURSOR_LOG] - Creating IndexCursorAdaptor IndexCursorAdaptor (absent) :
>
> [17:34:38] DEBUG [org.apache.directory.CURSOR_LOG] - Creating SetCursor SetCursor :
> Index : -1
> [17:34:38] DEBUG [org.apache.directory.CURSOR_LOG] - Creating EntryCursorAdaptor EntryCursorAdaptor
> [17:34:38] DEBUG [org.apache.directory.CURSOR_LOG] - Creating BaseEntryFilteringCursor BaseEntryFilteringCursor, no wrapped
> No filter
> No prefetched
> [17:34:38] DEBUG [org.apache.directory.server.OPERATION_LOG] - << SearchOperation successful
> [17:34:38] DEBUG [org.apache.directory.server.OPERATION_TIME] - Search operation took 377731 ns
> [17:34:38] ERROR [org.apache.directory.server.PROVIDER_LOG] - Failed to handle the syncrepl request
> org.apache.directory.api.ldap.model.exception.LdapException: java.io.IOException: ?????? ??θ? ã?? ?? ?????ϴ?
> at org.apache.directory.server.core.shared.DefaultCoreSession.search(DefaultCoreSession.java:1157)
> at org.apache.directory.server.ldap.replication.provider.SyncReplRequestHandler.doSimpleSearch(SyncReplRequestHandler.java:648)
> at org.apache.directory.server.ldap.replication.provider.SyncReplRequestHandler.doInitialRefresh(SyncReplRequestHandler.java:562)
> at org.apache.directory.server.ldap.replication.provider.SyncReplRequestHandler.handleSyncRequest(SyncReplRequestHandler.java:311)
> at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleReplication(SearchRequestHandler.java:238)
> at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:198)
> at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:93)
> at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:207)
> at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:56)
> at org.apache.mina.handler.demux.DemuxingIoHandler.messageReceived(DemuxingIoHandler.java:221)
> at org.apache.directory.server.ldap.LdapProtocolHandler.messageReceived(LdapProtocolHandler.java:217)
> at org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:690)
> at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:417)
> at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:47)
> at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:765)
> at org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:74)
> at org.apache.mina.core.session.IoEvent.run(IoEvent.java:63)
> at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.runTask(UnorderedThreadPoolExecutor.java:474)
> at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.run(UnorderedThreadPoolExecutor.java:428)
> at java.lang.Thread.run(Thread.java:744)
> Caused by: java.io.IOException: ?????? ??θ? ã?? ?? ?????ϴ?
> at java.io.WinNTFileSystem.createFileExclusively(Native Method)
> at java.io.File.createNewFile(File.java:1006)
> at java.io.File.createTempFile(File.java:1989)
> at java.io.File.createTempFile(File.java:2040)
> at org.apache.directory.server.core.shared.DefaultCoreSession.sortResults(DefaultCoreSession.java:1342)
> at org.apache.directory.server.core.shared.DefaultCoreSession.search(DefaultCoreSession.java:1130)
> ... 19 more
> [17:34:39] WARN [org.apache.directory.server.ldap.LdapProtocolHandler] - Unexpected exception forcing session to close: sending disconnect notice to client.
> org.apache.directory.api.ldap.model.exception.LdapException: java.io.IOException: ?????? ??θ? ã?? ?? ?????ϴ?
> at org.apache.directory.server.ldap.replication.provider.SyncReplRequestHandler.handleSyncRequest(SyncReplRequestHandler.java:349)
> at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handleReplication(SearchRequestHandler.java:238)
> at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:198)
> at org.apache.directory.server.ldap.handlers.request.SearchRequestHandler.handle(SearchRequestHandler.java:93)
> at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:207)
> at org.apache.directory.server.ldap.handlers.LdapRequestHandler.handleMessage(LdapRequestHandler.java:56)
> at org.apache.mina.handler.demux.DemuxingIoHandler.messageReceived(DemuxingIoHandler.java:221)
> at org.apache.directory.server.ldap.LdapProtocolHandler.messageReceived(LdapProtocolHandler.java:217)
> at org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:690)
> at org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:417)
> at org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:47)
> at org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:765)
> at org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:74)
> at org.apache.mina.core.session.IoEvent.run(IoEvent.java:63)
> at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.runTask(UnorderedThreadPoolExecutor.java:474)
> at org.apache.mina.filter.executor.UnorderedThreadPoolExecutor$Worker.run(UnorderedThreadPoolExecutor.java:428)
> at java.lang.Thread.run(Thread.java:744)
> Caused by: org.apache.directory.api.ldap.model.exception.LdapException: java.io.IOException: ?????? ??θ? ã?? ?? ?????ϴ?
> at org.apache.directory.server.core.shared.DefaultCoreSession.search(DefaultCoreSession.java:1157)
> at org.apache.directory.server.ldap.replication.provider.SyncReplRequestHandler.doSimpleSearch(SyncReplRequestHandler.java:648)
> at org.apache.directory.server.ldap.replication.provider.SyncReplRequestHandler.doInitialRefresh(SyncReplRequestHandler.java:562)
> at org.apache.directory.server.ldap.replication.provider.SyncReplRequestHandler.handleSyncRequest(SyncReplRequestHandler.java:311)
> ... 16 more
> Caused by: java.io.IOException: ?????? ??θ? ã?? ?? ?????ϴ?
> at java.io.WinNTFileSystem.createFileExclusively(Native Method)
> at java.io.File.createNewFile(File.java:1006)
> at java.io.File.createTempFile(File.java:1989)
> at java.io.File.createTempFile(File.java:2040)
> at org.apache.directory.server.core.shared.DefaultCoreSession.sortResults(DefaultCoreSession.java:1342)
> at org.apache.directory.server.core.shared.DefaultCoreSession.search(DefaultCoreSession.java:1130)
> ... 19 more
> [17:34:39] DEBUG [org.apache.directory.api.CODEC_LOG] - Encoded message
> MessageType : EXTENDED_RESPONSE
> Message ID : 0
> Extended Response
> ResponseName :'1.3.6.1.4.1.1466.20036'
> Ldap Result
> Result code : (PROTOCOL_ERROR) protocolError
> Matched Dn : 'null'
> Diagnostic message : 'PROTOCOL_ERROR: The server will disconnect!'
> : 0x30 0x4F 0x02 0x01 0x00 0x78 0x4A 0x0A 0x01 0x02 0x04 0x00 0x04 0x2B 0x50 0x52 0x4F 0x54 0x4F 0x43 0x4F 0x4C 0x5F 0x45 0x52 0x52 0x4F 0x52 0x3A 0x20 0x54 0x68 0x65 0x20 0x73 0x65 0x72 0x76 0x65 0x72 0x20 0x77 0x69 0x6C 0x6C 0x20 0x64 0x69 0x73 0x63 0x6F 0x6E 0x6E 0x65 0x63 0x74 0x21 0x8A 0x16 0x31 0x2E 0x33 0x2E 0x36 0x2E 0x31 0x2E 0x34 0x2E 0x31 0x2E 0x31 0x34 0x36 0x36 0x2E 0x32 0x30 0x30 0x33 0x36
> [17:34:39] DEBUG [org.apache.directory.server.ldap.LdapProtocolHandler] - Cleaning the LdapSession : <0.9.2342.19200300.100.1.1=admin,2.5.4.11=system,127.0.0.1/127.0.0.1:60165> session
> [17:34:39] DEBUG [org.apache.mina.core.filterchain.IoFilterEvent] - Event MESSAGE_RECEIVED has been fired for session 151
> [17:34:39] INFO [org.apache.directory.server.ldap.handlers.LdapResponseHandler] - ignoring the message MessageType : EXTENDED_RESPONSE
> Message ID : 0
> Extended Response
> ResponseName :'1.3.6.1.4.1.1466.20036'
> Ldap Result
> Result code : (PROTOCOL_ERROR) protocolError
> Matched Dn : 'null'
> Diagnostic message : 'PROTOCOL_ERROR: The server will disconnect!'
> received from null session
> [17:34:39] WARN [org.apache.directory.server.ldap.LdapProtocolHandler] - Null LdapSession given to cleanUpSession.
> It came out of user mailing list.
> http://mail-archives.apache.org/mod_mbox/directory-users/201409.mbox/browser
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)