Hi,
here is an exemple of logs I get now when running replication. This is a
scenario where I start a consumer, then a producer, then I stop the
producer. As expected, the consumer cannot connect to the producer at
first, then connects, then detect the disconnection.
There are a few improvements I want to work on now, like dumping the
cookie as a string in any case, improve the IntermediateResponse dump,
and also create a logger per consumer, or at least use MDC to b able to
follow one consumer in the logs.
All those logs are dumped into a dedicated logger, btw.
_ _ ____ ____
/ \ _ __ ___ ___| |__ ___| _ \/ ___|
/ _ \ | '_ \ / _` |/ __| '_ \ / _ \ | | \___ \
/ ___ \| |_) | (_| | (__| | | | __/ |_| |___) |
/_/ \_\ .__/ \__,_|\___|_| |_|\___|____/|____/
|_|
[17:07:40] DEBUG [CONSUMER_LOG] - Configuring consumer [rid:0,
base:'ou=system', filter:(objectClass=*), scope:sub,
alias:NEVER_DEREF_ALIASES, chase referrals:false, attributes:{*},
refresh:60000, MMR, provider:localhost:11389,
user:'uid=admin,ou=system', TLS:false]
[17:07:40] INFO [CONSUMER_LOG] - starting the replication consumer
with Consumer [rid:0, base:'ou=system', filter:(objectClass=*),
scope:sub, alias:NEVER_DEREF_ALIASES, chase referrals:false,
attributes:{*}, refresh:60000, MMR, provider:localhost:11389,
user:'uid=admin,ou=system', TLS:false]
_ _ _ __ ____ ___
/ \ _ __ ___ ___| |__ ___| |/ /| _ \ / __|
/ _ \ | '_ \ / _` |/ __| '_ \ / _ \ ' / | | | / /
/ ___ \| |_) | (_| | (__| | | | __/ . \ | |_| \ \__
/_/ \_\ .__/ \__,_|\___|_| |_|\___|_|\_\|____/ \___|
|_|
[17:08:14] ERROR [CONSUMER_LOG] - Failed to connect to the server
localhost:11389, cause : Cannot connect on the server: Connection
refused
[17:08:14] ERROR
[org.apache.directory.server.ldap.replication.consumer.ReplicationConsumerImpl]
- Failed to connect to the server localhost:11389, cause : Cannot
connect on the server: Connection refused
[17:08:14] DEBUG [CONSUMER_LOG] - Consumer 0 cannot connect to
localhost:11389, wait 5 seconds.
[17:08:29] ERROR [CONSUMER_LOG] - Failed to connect to the server
localhost:11389, cause : Cannot connect on the server: Connection
refused
[17:08:29] ERROR
[org.apache.directory.server.ldap.replication.consumer.ReplicationConsumerImpl]
- Failed to connect to the server localhost:11389, cause : Cannot
connect on the server: Connection refused
[17:08:29] DEBUG [CONSUMER_LOG] - Consumer 0 cannot connect to
localhost:11389, wait 5 seconds.
[17:08:34] INFO [CONSUMER_LOG] - Consumer 0 connected to
producerlocalhost:11389
[17:08:34] DEBUG [CONSUMER_LOG] - Starting the SyncRepl process for
consumer 0
[17:08:34] DEBUG [CONSUMER_LOG] - Cookie for consumer 0 :
rid=009,csn=20121130171126.104000Z#000000#000#000000
[17:08:34] DEBUG [CONSUMER_LOG] - Cookie read : '[114, 105, 100, 61,
48, 48, 57, 44, 99, 115, 110, 61, 50, 48, 49, 50, 49, 49, 51, 48,
49, 55, 49, 49, 50, 54, 46, 49, 48, 52, 48, 48, 48, 90, 35, 48, 48,
48, 48, 48, 48, 35, 48, 48, 48, 35, 48, 48, 48, 48, 48, 48]'
[17:08:34] DEBUG [CONSUMER_LOG] - Starting synchronization mode
REFRESH_AND_PERSIST, reloadHint false
[17:08:34] DEBUG [CONSUMER_LOG] - searching on localhost:11389 with
searchRequest, cookie
'rid=009,csn=20121130171126.104000Z#000000#000#000000'
[17:08:34] DEBUG [CONSUMER_LOG] - Response from localhost:11389 :
MessageType : INTERMEDIATE_RESPONSE
Message ID : 2
Intermediate Response
Response name :'1.3.6.1.4.1.4203.1.9.1.4'
ResponseValue :'0x80 0x34 0x72 0x69 0x64 0x3D 0x30 0x30 0x39
0x2C 0x63 0x73 0x6E 0x3D 0x32 0x30 0x31 0x32 0x31 0x31 0x33 0x30
0x31 0x37 0x31 0x31 0x32 0x36 0x2E 0x31 0x30 0x34 0x30 0x30 0x30
0x5A 0x23 0x30 0x30 0x30 0x30 0x30 0x30 0x23 0x30 0x30 0x30 0x23
0x30 0x30 0x30 0x30 0x30 0x30 '
Ldap Result
Result code : (SUCCESS) success
Matched Dn : 'null'
Diagnostic message : 'null'
[17:08:34] DEBUG [CONSUMER_LOG] - setting the cookie from the sync
info: rid=009,csn=20121130171126.104000Z#000000#000#000000
[17:08:34] DEBUG [CONSUMER_LOG] - Storing the cookie
'rid=009,csn=20121130171126.104000Z#000000#000#000000'
[17:09:21] DEBUG [CONSUMER_LOG] - Consumer 0 session with
localhost:11389 has been closed
[17:09:21] DEBUG [CONSUMER_LOG] - Response from localhost:11389 :
MessageType : SEARCH_RESULT_DONE
Message ID : -1
Search Result Done
Ldap Result
Result code : (PROTOCOL_ERROR) protocolError
Matched Dn : 'null'
Diagnostic message : 'PROTOCOL_ERROR: The server will
disconnect!'
[17:09:21] DEBUG [CONSUMER_LOG] - Search sync on localhost:11389 has
been canceled
[17:09:21] INFO [CONSUMER_LOG] - starting the replication consumer
with Consumer [rid:0, base:'ou=system', filter:(objectClass=*),
scope:sub, alias:NEVER_DEREF_ALIASES, chase referrals:false,
attributes:{*}, refresh:60000, MMR, provider:localhost:11389,
user:'uid=admin,ou=system', TLS:false]
[17:09:48] ERROR [CONSUMER_LOG] - Failed to connect to the server
localhost:11389, cause : Cannot connect on the server: Connection
refused
[17:09:48] ERROR
[org.apache.directory.server.ldap.replication.consumer.ReplicationConsumerImpl]
- Failed to connect to the server localhost:11389, cause : Cannot
connect on the server: Connection refused
[17:09:48] DEBUG [CONSUMER_LOG] - Consumer 0 cannot connect to
localhost:11389, wait 5 seconds.
--
Regards,
Cordialement,
Emmanuel Lécharny
www.iktek.com
