[Date Prev][Date Next] [Chronological] [Thread] [Top]

Re: bootstrapping mirrormode



In general it seems server-2 won't find changes to server-1 made while server-2 is down... which kinda defeats the purpose of mirrormode.

The only thing I can see in the log, which makes me wonder is entries like:

bdb_index_read: failed (-30989)

Attached is a log-output (None,trace,sync) after stopping, server-2, making a change on server-1 and starting server-2.

/Peter


Dec  4 11:58:54 server1 slapd[25776]: do_bind: SASL/EXTERNAL bind: dn="cn=config" sasl_ssf=0
Dec  4 11:58:54 server1 slapd[25776]: send_ldap_response: msgid=1 tag=97 err=0
Dec  4 11:58:54 server1 slapd[25776]: <== slap_sasl_bind: rc=0
Dec  4 11:58:54 server1 slapd[25776]: connection_get(15): got connid=1011
Dec  4 11:58:54 server1 slapd[25776]: connection_read(15): checking for input on id=1011
Dec  4 11:58:54 server1 slapd[25776]: op tag 0x63, time 1259927934
Dec  4 11:58:54 server1 slapd[25776]: conn=1011 op=1 do_search
Dec  4 11:58:54 server1 slapd[25776]: >>> dnPrettyNormal: <dc=app,dc=example,dc=com>
Dec  4 11:58:54 server1 slapd[25776]: <<< dnPrettyNormal: <dc=app,dc=example,dc=com>, <dc=app,dc=example,dc=com>
Dec  4 11:58:54 server1 slapd[25776]: => get_ctrls
Dec  4 11:58:54 server1 slapd[25776]: => get_ctrls: oid="1.3.6.1.4.1.4203.1.9.1.1" (noncritical)
Dec  4 11:58:54 server1 slapd[25776]: => get_ctrls: oid="2.16.840.1.113730.3.4.2" (critical)
Dec  4 11:58:54 server1 slapd[25776]: <= get_ctrls: n=2 rc=0 err=""
Dec  4 11:58:54 server1 slapd[25776]: => bdb_search
Dec  4 11:58:54 server1 slapd[25776]: bdb_dn2entry("dc=app,dc=example,dc=com")
Dec  4 11:58:54 server1 slapd[25776]: send_ldap_result: conn=1011 op=1 p=3
Dec  4 11:58:54 server1 slapd[25776]: srs csn 20091204114115.348793Z#000000#001#000000
Dec  4 11:58:54 server1 slapd[25776]: log csn 20091204114042.556305Z#000000#001#000000
Dec  4 11:58:54 server1 slapd[25776]: cmp -1, too old
Dec  4 11:58:54 server1 slapd[25776]: log csn 20091204114115.348793Z#000000#001#000000
Dec  4 11:58:54 server1 slapd[25776]: cmp 0, too old
Dec  4 11:58:54 server1 slapd[25776]: log csn 20091204115801.657341Z#000000#001#000000
Dec  4 11:58:54 server1 slapd[25776]: => bdb_search
Dec  4 11:58:54 server1 slapd[25776]: bdb_dn2entry("dc=app,dc=example,dc=com")
Dec  4 11:58:54 server1 slapd[25776]: search_candidates: base="dc=app,dc=example,dc=com" (0x00000001) scope=2
Dec  4 11:58:54 server1 slapd[25776]: => bdb_dn2idl("dc=app,dc=example,dc=com")
Dec  4 11:58:54 server1 slapd[25776]: => bdb_equality_candidates (entryUUID)
Dec  4 11:58:54 server1 slapd[25776]: => key_read
Dec  4 11:58:54 server1 slapd[25776]: <= bdb_index_read 1 candidates
Dec  4 11:58:54 server1 slapd[25776]: <= bdb_equality_candidates: id=1, first=1, last=1
Dec  4 11:58:54 server1 slapd[25776]: => bdb_presence_candidates (objectClass)
Dec  4 11:58:54 server1 slapd[25776]: bdb_search_candidates: id=1 first=1 last=1
Dec  4 11:58:54 server1 slapd[25776]: send_ldap_result: conn=1011 op=1 p=3
Dec  4 11:58:54 server1 slapd[25776]: => bdb_search
Dec  4 11:58:54 server1 slapd[25776]: bdb_dn2entry("dc=app,dc=example,dc=com")
Dec  4 11:58:54 server1 slapd[25776]: search_candidates: base="dc=app,dc=example,dc=com" (0x00000001) scope=2
Dec  4 11:58:54 server1 slapd[25776]: => bdb_dn2idl("dc=app,dc=example,dc=com")
Dec  4 11:58:54 server1 slapd[25776]: => bdb_equality_candidates (entryCSN)
Dec  4 11:58:54 server1 slapd[25776]: => key_read
Dec  4 11:58:54 server1 slapd[25776]: <= bdb_index_read: failed (-30989)
Dec  4 11:58:54 server1 slapd[25776]: <= bdb_equality_candidates: id=0, first=0, last=0
Dec  4 11:58:54 server1 slapd[25776]: bdb_search_candidates: id=0 first=1 last=0
Dec  4 11:58:54 server1 slapd[25776]: bdb_search: no candidates
Dec  4 11:58:54 server1 slapd[25776]: send_ldap_result: conn=1011 op=1 p=3
Dec  4 11:58:54 server1 slapd[25776]: => bdb_search
Dec  4 11:58:54 server1 slapd[25776]: bdb_dn2entry("dc=app,dc=example,dc=com")
Dec  4 11:58:54 server1 slapd[25776]: search_candidates: base="dc=app,dc=example,dc=com" (0x00000001) scope=2
Dec  4 11:58:54 server1 slapd[25776]: => bdb_dn2idl("dc=app,dc=example,dc=com")
Dec  4 11:58:54 server1 slapd[25776]: => bdb_inequality_candidates (entryCSN)
Dec  4 11:58:54 server1 slapd[25776]: => key_read
Dec  4 11:58:54 server1 slapd[25776]: <= bdb_index_read 86 candidates
Dec  4 11:58:54 server1 slapd[25776]: <= bdb_inequality_candidates: id=86, first=2, last=87
Dec  4 11:58:54 server1 slapd[25776]: bdb_search_candidates: id=-1 first=2 last=87
Dec  4 11:58:54 server1 slapd[25776]: send_ldap_result: conn=1011 op=1 p=3
Dec  4 11:58:54 server1 slapd[25776]: => bdb_search
Dec  4 11:58:54 server1 slapd[25776]: bdb_dn2entry("dc=app,dc=example,dc=com")
Dec  4 11:58:54 server1 slapd[25776]: search_candidates: base="dc=app,dc=example,dc=com" (0x00000001) scope=2
Dec  4 11:58:54 server1 slapd[25776]: => bdb_dn2idl("dc=app,dc=example,dc=com")
Dec  4 11:58:54 server1 slapd[25776]: => bdb_inequality_candidates (entryCSN)
Dec  4 11:58:54 server1 slapd[25776]: => key_read
Dec  4 11:58:54 server1 slapd[25776]: <= bdb_index_read 1 candidates
Dec  4 11:58:54 server1 slapd[25776]: => key_read
Dec  4 11:58:54 server1 slapd[25776]: <= bdb_index_read: failed (-30989)
Dec  4 11:58:54 server1 slapd[25776]: <= bdb_inequality_candidates: id=1, first=1, last=1
Dec  4 11:58:54 server1 slapd[25776]: => bdb_presence_candidates (objectClass)
Dec  4 11:58:54 server1 slapd[25776]: bdb_search_candidates: id=1 first=1 last=1
Dec  4 11:58:54 server1 slapd[25776]: Entry dc=app,dc=example,dc=com changed by peer, ignored
Dec  4 11:58:54 server1 slapd[25776]: send_ldap_result: conn=1011 op=1 p=3
Dec  4 11:58:54 server1 slapd[25776]: syncprov_search_response: cookie=rid=003,sid=001,csn=20091204115801.657341Z#000000#001#000000
Dec  4 11:58:54 server1 slapd[25776]: send_ldap_intermediate: err=0 oid=1.3.6.1.4.1.4203.1.9.1.4 len=64
Dec  4 11:58:54 server1 slapd[25776]: send_ldap_response: msgid=2 tag=121 err=0
Dec  4 11:58:54 server1 slapd[25776]: slap_listener_activate(9):
Dec  4 11:58:54 server1 slapd[25776]: >>> slap_listener(ldaps://server1.example.com:636/)