report.html

topo_tls_ldapi = <lib389.topologies.TopologyMain object at 0x7ff7fa810940>

def test_conflict_entries(topo_tls_ldapi):
"""Check that the report has conflict entries

:id: 4eda0c5d-0824-4cfd-896e-845faf49ddaf
:setup: Two master replication
:steps:
1. Pause replication between master and replica
2. Add two entries to master and two entries to replica
3. Delete first entry from master
4. Add a child to the first entry
5. Resume replication between master and replica
6. Generate the report
7. Check that the entries DN are mentioned in the report
:expectedresults:
1. It should be successful
2. It should be successful
3. It should be successful
4. It should be successful
5. It should be successful
6. It should be successful
7. The entries DN should be mentioned in the report
"""

m1 = topo_tls_ldapi.ms["master1"]
m2 = topo_tls_ldapi.ms["master2"]

topo_tls_ldapi.pause_all_replicas()

_create_container(m1, DEFAULT_SUFFIX, 'conflict_parent0')
_create_container(m2, DEFAULT_SUFFIX, 'conflict_parent0')
cont_p_m1 = _create_container(m1, DEFAULT_SUFFIX, 'conflict_parent1')
cont_p_m2 = _create_container(m2, DEFAULT_SUFFIX, 'conflict_parent1')
_delete_container(cont_p_m1)
_create_container(m2, cont_p_m2.dn, 'conflict_child0')

topo_tls_ldapi.resume_all_replicas()
time.sleep(5)

for tool_cmd in replcheck_cmd_list(topo_tls_ldapi):
> result = subprocess.check_output(tool_cmd, encoding='utf-8')

suites/ds_tools/replcheck_test.py:331:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
/usr/lib64/python3.8/subprocess.py:411: in check_output
return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

input = None, capture_output = False, timeout = None, check = True
popenargs = (['/usr/bin/ds-replcheck', 'offline', '-b', 'dc=example,dc=com', '--conflicts', '--rid', ...],)
kwargs = {'encoding': 'utf-8', 'stdout': -1}
process = <subprocess.Popen object at 0x7ff7fa5fffd0>, stdout = ''
stderr = None, retcode = 1

def run(*popenargs,
input=None, capture_output=False, timeout=None, check=False, **kwargs):
"""Run command with arguments and return a CompletedProcess instance.

The returned instance will have attributes args, returncode, stdout and
stderr. By default, stdout and stderr are not captured, and those attributes
will be None. Pass stdout=PIPE and/or stderr=PIPE in order to capture them.

If check is True and the exit code was non-zero, it raises a
CalledProcessError. The CalledProcessError object will have the return code
in the returncode attribute, and output & stderr attributes if those streams
were captured.

If timeout is given, and the process takes too long, a TimeoutExpired
exception will be raised.

There is an optional argument "input", allowing you to
pass bytes or a string to the subprocess's stdin. If you use this argument
you may not also use the Popen constructor's "stdin" argument, as
it will be used internally.

By default, all communication is in bytes, and therefore any "input" should
be bytes, and the stdout and stderr will be bytes. If in text mode, any
"input" should be a string, and stdout and stderr will be strings decoded
according to locale encoding, or by "encoding" if set. Text mode is
triggered by setting any of text, encoding, errors or universal_newlines.

The other arguments are the same as for the Popen constructor.
"""
if input is not None:
if kwargs.get('stdin') is not None:
raise ValueError('stdin and input arguments may not both be used.')
kwargs['stdin'] = PIPE

if capture_output:
if kwargs.get('stdout') is not None or kwargs.get('stderr') is not None:
raise ValueError('stdout and stderr arguments may not be used '
'with capture_output.')
kwargs['stdout'] = PIPE
kwargs['stderr'] = PIPE

with Popen(*popenargs, **kwargs) as process:
try:
stdout, stderr = process.communicate(input, timeout=timeout)
except TimeoutExpired as exc:
process.kill()
if _mswindows:
# Windows accumulates the output in a single blocking
# read() call run on child threads, with the timeout
# being done in a join() on those threads. communicate()
# _after_ kill() is required to collect that and add it
# to the exception.
exc.stdout, exc.stderr = process.communicate()
else:
# POSIX _communicate already populated the output so
# far into the TimeoutExpired exception.
process.wait()
raise
except: # Including KeyboardInterrupt, communicate handled that.
process.kill()
# We don't call process.wait() as .__exit__ does that for us.
raise
retcode = process.poll()
if check and retcode:
> raise CalledProcessError(retcode, process.args,
output=stdout, stderr=stderr)
E subprocess.CalledProcessError: Command '['/usr/bin/ds-replcheck', 'offline', '-b', 'dc=example,dc=com', '--conflicts', '--rid', '1', '-m', '/tmp/export_master1.ldif', '-r', '/tmp/export_master2.ldif']' returned non-zero exit status 1.

/usr/lib64/python3.8/subprocess.py:512: CalledProcessError
------------------------------Captured stderr call------------------------------
ldiffile: /tmp/export_master1.ldif ldiffile: /tmp/export_master2.ldif Traceback (most recent call last): File "/usr/bin/ds-replcheck", line 1644, in <module> main() File "/usr/bin/ds-replcheck", line 1640, in main args.func(args) File "/usr/bin/ds-replcheck", line 1534, in offline_report do_offline_report(opts, OUTPUT_FILE) File "/usr/bin/ds-replcheck", line 774, in do_offline_report diff = cmp_entry(mresult['entry'], rresult['entry'], opts) File "/usr/bin/ds-replcheck", line 535, in cmp_entry rlist = list(rentry.data.keys()) AttributeError: 'NoneType' object has no attribute 'data'

topo_tls_ldapi = <lib389.topologies.TopologyMain object at 0x7ff7fa810940>

def test_inconsistencies(topo_tls_ldapi):
"""Check that the report mentions inconsistencies with attributes

:id: c8fe3e84-b346-4969-8f5d-3462b643a1d2
:setup: Two master replication
:steps:
1. Add an entry to master and wait for replication
2. Pause replication between master and replica
3. Set different description attr values to master and replica
4. Add telephoneNumber attribute to master and not to replica
5. Generate the report
6. Check that attribute values are mentioned in the report
7. Generate the report with -i option to ignore some attributes
8. Check that attribute values are mentioned in the report
:expectedresults:
1. It should be successful
2. It should be successful
3. It should be successful
4. It should be successful
5. It should be successful
6. The attribute values should be mentioned in the report
7. It should be successful
8. The attribute values should not be mentioned in the report
"""

m1 = topo_tls_ldapi.ms["master1"]
m2 = topo_tls_ldapi.ms["master2"]
attr_m1 = "m1_inconsistency"
attr_m2 = "m2_inconsistency"
attr_first = "first ordered valued"
attr_second = "second ordered valued"
attr_m1_only = "123123123"

try:
users_m1 = UserAccounts(m1, DEFAULT_SUFFIX)
users_m2 = UserAccounts(m2, DEFAULT_SUFFIX)
user_m1 = users_m1.create(properties=TEST_USER_PROPERTIES)
time.sleep(1)
user_m2 = users_m2.get(user_m1.rdn)
topo_tls_ldapi.pause_all_replicas()
user_m1.set("description", attr_m1)
user_m2.set("description", attr_m2)
user_m1.set("telephonenumber", attr_m1_only)
# Add the same multi-valued attrs, but out of order
user_m1.set("cn", [attr_first, attr_second])
user_m2.set("cn", [attr_second, attr_first])
time.sleep(2)

for tool_cmd in replcheck_cmd_list(topo_tls_ldapi):
> result = subprocess.check_output(tool_cmd, encoding='utf-8').lower()

suites/ds_tools/replcheck_test.py:384:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
/usr/lib64/python3.8/subprocess.py:411: in check_output
return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

input = None, capture_output = False, timeout = None, check = True
popenargs = (['/usr/bin/ds-replcheck', 'offline', '-b', 'dc=example,dc=com', '--conflicts', '--rid', ...],)
kwargs = {'encoding': 'utf-8', 'stdout': -1}
process = <subprocess.Popen object at 0x7ff7f8e8a130>, stdout = ''
stderr = None, retcode = 1

def run(*popenargs,
input=None, capture_output=False, timeout=None, check=False, **kwargs):
"""Run command with arguments and return a CompletedProcess instance.

The returned instance will have attributes args, returncode, stdout and
stderr. By default, stdout and stderr are not captured, and those attributes
will be None. Pass stdout=PIPE and/or stderr=PIPE in order to capture them.

If check is True and the exit code was non-zero, it raises a
CalledProcessError. The CalledProcessError object will have the return code
in the returncode attribute, and output & stderr attributes if those streams
were captured.

If timeout is given, and the process takes too long, a TimeoutExpired
exception will be raised.

There is an optional argument "input", allowing you to
pass bytes or a string to the subprocess's stdin. If you use this argument
you may not also use the Popen constructor's "stdin" argument, as
it will be used internally.

By default, all communication is in bytes, and therefore any "input" should
be bytes, and the stdout and stderr will be bytes. If in text mode, any
"input" should be a string, and stdout and stderr will be strings decoded
according to locale encoding, or by "encoding" if set. Text mode is
triggered by setting any of text, encoding, errors or universal_newlines.

The other arguments are the same as for the Popen constructor.
"""
if input is not None:
if kwargs.get('stdin') is not None:
raise ValueError('stdin and input arguments may not both be used.')
kwargs['stdin'] = PIPE

if capture_output:
if kwargs.get('stdout') is not None or kwargs.get('stderr') is not None:
raise ValueError('stdout and stderr arguments may not be used '
'with capture_output.')
kwargs['stdout'] = PIPE
kwargs['stderr'] = PIPE

with Popen(*popenargs, **kwargs) as process:
try:
stdout, stderr = process.communicate(input, timeout=timeout)
except TimeoutExpired as exc:
process.kill()
if _mswindows:
# Windows accumulates the output in a single blocking
# read() call run on child threads, with the timeout
# being done in a join() on those threads. communicate()
# _after_ kill() is required to collect that and add it
# to the exception.
exc.stdout, exc.stderr = process.communicate()
else:
# POSIX _communicate already populated the output so
# far into the TimeoutExpired exception.
process.wait()
raise
except: # Including KeyboardInterrupt, communicate handled that.
process.kill()
# We don't call process.wait() as .__exit__ does that for us.
raise
retcode = process.poll()
if check and retcode:
> raise CalledProcessError(retcode, process.args,
output=stdout, stderr=stderr)
E subprocess.CalledProcessError: Command '['/usr/bin/ds-replcheck', 'offline', '-b', 'dc=example,dc=com', '--conflicts', '--rid', '1', '-m', '/tmp/export_master1.ldif', '-r', '/tmp/export_master2.ldif']' returned non-zero exit status 1.

/usr/lib64/python3.8/subprocess.py:512: CalledProcessError
------------------------------Captured stderr call------------------------------
ldiffile: /tmp/export_master1.ldif ldiffile: /tmp/export_master2.ldif Traceback (most recent call last): File "/usr/bin/ds-replcheck", line 1644, in <module> main() File "/usr/bin/ds-replcheck", line 1640, in main args.func(args) File "/usr/bin/ds-replcheck", line 1534, in offline_report do_offline_report(opts, OUTPUT_FILE) File "/usr/bin/ds-replcheck", line 774, in do_offline_report diff = cmp_entry(mresult['entry'], rresult['entry'], opts) File "/usr/bin/ds-replcheck", line 535, in cmp_entry rlist = list(rentry.data.keys()) AttributeError: 'NoneType' object has no attribute 'data'

topo_tls_ldapi = <lib389.topologies.TopologyMain object at 0x7ff7fa810940>

def test_check_missing_tombstones(topo_tls_ldapi):
"""Check missing tombstone entries is not reported.

:id: 93067a5a-416e-4243-9418-c4dfcf42e093
:setup: Two master replication
:steps:
1. Pause replication between master and replica
2. Add and delete an entry on the master
3. Run ds-replcheck
4. Verify there are NO complaints about missing entries/tombstones
:expectedresults:
1. It should be successful
2. It should be successful
3. It should be successful
4. It should be successful
"""
m1 = topo_tls_ldapi.ms["master1"]
m2 = topo_tls_ldapi.ms["master2"]

try:
topo_tls_ldapi.pause_all_replicas()
users_m1 = UserAccounts(m1, DEFAULT_SUFFIX)
user0 = users_m1.create_test_user(1000)
user0.delete()
for tool_cmd in replcheck_cmd_list(topo_tls_ldapi):
> result = subprocess.check_output(tool_cmd, encoding='utf-8').lower()

suites/ds_tools/replcheck_test.py:460:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
/usr/lib64/python3.8/subprocess.py:411: in check_output
return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

input = None, capture_output = False, timeout = None, check = True
popenargs = (['/usr/bin/ds-replcheck', 'offline', '-b', 'dc=example,dc=com', '--conflicts', '--rid', ...],)
kwargs = {'encoding': 'utf-8', 'stdout': -1}
process = <subprocess.Popen object at 0x7ff7f8f3c4f0>, stdout = ''
stderr = None, retcode = 1

def run(*popenargs,
input=None, capture_output=False, timeout=None, check=False, **kwargs):
"""Run command with arguments and return a CompletedProcess instance.

The returned instance will have attributes args, returncode, stdout and
stderr. By default, stdout and stderr are not captured, and those attributes
will be None. Pass stdout=PIPE and/or stderr=PIPE in order to capture them.

If check is True and the exit code was non-zero, it raises a
CalledProcessError. The CalledProcessError object will have the return code
in the returncode attribute, and output & stderr attributes if those streams
were captured.

If timeout is given, and the process takes too long, a TimeoutExpired
exception will be raised.

There is an optional argument "input", allowing you to
pass bytes or a string to the subprocess's stdin. If you use this argument
you may not also use the Popen constructor's "stdin" argument, as
it will be used internally.

By default, all communication is in bytes, and therefore any "input" should
be bytes, and the stdout and stderr will be bytes. If in text mode, any
"input" should be a string, and stdout and stderr will be strings decoded
according to locale encoding, or by "encoding" if set. Text mode is
triggered by setting any of text, encoding, errors or universal_newlines.

The other arguments are the same as for the Popen constructor.
"""
if input is not None:
if kwargs.get('stdin') is not None:
raise ValueError('stdin and input arguments may not both be used.')
kwargs['stdin'] = PIPE

if capture_output:
if kwargs.get('stdout') is not None or kwargs.get('stderr') is not None:
raise ValueError('stdout and stderr arguments may not be used '
'with capture_output.')
kwargs['stdout'] = PIPE
kwargs['stderr'] = PIPE

with Popen(*popenargs, **kwargs) as process:
try:
stdout, stderr = process.communicate(input, timeout=timeout)
except TimeoutExpired as exc:
process.kill()
if _mswindows:
# Windows accumulates the output in a single blocking
# read() call run on child threads, with the timeout
# being done in a join() on those threads. communicate()
# _after_ kill() is required to collect that and add it
# to the exception.
exc.stdout, exc.stderr = process.communicate()
else:
# POSIX _communicate already populated the output so
# far into the TimeoutExpired exception.
process.wait()
raise
except: # Including KeyboardInterrupt, communicate handled that.
process.kill()
# We don't call process.wait() as .__exit__ does that for us.
raise
retcode = process.poll()
if check and retcode:
> raise CalledProcessError(retcode, process.args,
output=stdout, stderr=stderr)
E subprocess.CalledProcessError: Command '['/usr/bin/ds-replcheck', 'offline', '-b', 'dc=example,dc=com', '--conflicts', '--rid', '1', '-m', '/tmp/export_master1.ldif', '-r', '/tmp/export_master2.ldif']' returned non-zero exit status 1.

/usr/lib64/python3.8/subprocess.py:512: CalledProcessError
------------------------------Captured stderr call------------------------------
ldiffile: /tmp/export_master1.ldif ldiffile: /tmp/export_master2.ldif Traceback (most recent call last): File "/usr/bin/ds-replcheck", line 1644, in <module> main() File "/usr/bin/ds-replcheck", line 1640, in main args.func(args) File "/usr/bin/ds-replcheck", line 1534, in offline_report do_offline_report(opts, OUTPUT_FILE) File "/usr/bin/ds-replcheck", line 774, in do_offline_report diff = cmp_entry(mresult['entry'], rresult['entry'], opts) File "/usr/bin/ds-replcheck", line 535, in cmp_entry rlist = list(rentry.data.keys()) AttributeError: 'NoneType' object has no attribute 'data'

self = <tests.suites.replication.conflict_resolve_test.TestTwoMasters object at 0x7ff7ea0edbb0>
topology_m2 = <lib389.topologies.TopologyMain object at 0x7ff7ea390ac0>
base_m2 = <lib389.idm.nscontainer.nsContainer object at 0x7ff7ea0ed190>

def test_complex_add_modify_modrdn_delete(self, topology_m2, base_m2):
"""Check that conflict properly resolved for complex operations
which involve add, modify, modrdn and delete

:id: 77f09b18-03d1-45da-940b-1ad2c2908eb1
:setup: Two master replication, test container for entries, enable plugin logging,
audit log, error log for replica and access log for internal
:steps:
1. Add ten users to m1 and wait for replication to happen
2. Pause replication
3. Test add-del on m1 and add on m2
4. Test add-mod on m1 and add on m2
5. Test add-modrdn on m1 and add on m2
6. Test multiple add, modrdn
7. Test Add-del on both masters
8. Test modrdn-modrdn
9. Test modrdn-del
10. Resume replication
11. Check that the entries on both masters are the same and replication is working
:expectedresults:
1. It should pass
2. It should pass
3. It should pass
4. It should pass
5. It should pass
6. It should pass
7. It should pass
8. It should pass
9. It should pass
10. It should pass
11. It should pass
"""

M1 = topology_m2.ms["master1"]
M2 = topology_m2.ms["master2"]

test_users_m1 = UserAccounts(M1, base_m2.dn, rdn=None)
test_users_m2 = UserAccounts(M2, base_m2.dn, rdn=None)
repl = ReplicationManager(SUFFIX)

for user_num in range(1100, 1110):
_create_user(test_users_m1, user_num)

repl.test_replication(M1, M2)
topology_m2.pause_all_replicas()

log.info("Test add-del on M1 and add on M2")
user_num += 1
_create_user(test_users_m1, user_num)
_delete_user(test_users_m1, user_num, sleep=True)
_create_user(test_users_m2, user_num, sleep=True)

user_num += 1
_create_user(test_users_m1, user_num, sleep=True)
_create_user(test_users_m2, user_num, sleep=True)
_delete_user(test_users_m1, user_num, sleep=True)

user_num += 1
_create_user(test_users_m2, user_num, sleep=True)
_create_user(test_users_m1, user_num)
_delete_user(test_users_m1, user_num)

log.info("Test add-mod on M1 and add on M2")
user_num += 1
_create_user(test_users_m1, user_num)
_modify_user(test_users_m1, user_num, sleep=True)
_create_user(test_users_m2, user_num, sleep=True)

user_num += 1
_create_user(test_users_m1, user_num, sleep=True)
_create_user(test_users_m2, user_num, sleep=True)
_modify_user(test_users_m1, user_num, sleep=True)

user_num += 1
_create_user(test_users_m2, user_num, sleep=True)
_create_user(test_users_m1, user_num)
_modify_user(test_users_m1, user_num)

log.info("Test add-modrdn on M1 and add on M2")
user_num += 1
_create_user(test_users_m1, user_num)
_rename_user(test_users_m1, user_num, user_num+20, sleep=True)
_create_user(test_users_m2, user_num, sleep=True)

user_num += 1
_create_user(test_users_m1, user_num, sleep=True)
_create_user(test_users_m2, user_num, sleep=True)
_rename_user(test_users_m1, user_num, user_num+20, sleep=True)

user_num += 1
_create_user(test_users_m2, user_num, sleep=True)
_create_user(test_users_m1, user_num)
_rename_user(test_users_m1, user_num, user_num+20)

log.info("Test multiple add, modrdn")
user_num += 1
_create_user(test_users_m1, user_num, sleep=True)
_create_user(test_users_m2, user_num, sleep=True)
_rename_user(test_users_m1, user_num, user_num+20)
_create_user(test_users_m1, user_num, sleep=True)
_modify_user(test_users_m2, user_num, sleep=True)

log.info("Add - del on both masters")
user_num += 1
_create_user(test_users_m1, user_num)
_delete_user(test_users_m1, user_num, sleep=True)
_create_user(test_users_m2, user_num)
_delete_user(test_users_m2, user_num, sleep=True)

log.info("Test modrdn - modrdn")
user_num += 1
_rename_user(test_users_m1, 1109, 1129, sleep=True)
_rename_user(test_users_m2, 1109, 1129, sleep=True)

log.info("Test modrdn - del")
user_num += 1
_rename_user(test_users_m1, 1100, 1120, sleep=True)
_delete_user(test_users_m2, 1100)

user_num += 1
_delete_user(test_users_m2, 1101, sleep=True)
_rename_user(test_users_m1, 1101, 1121)

topology_m2.resume_all_replicas()

repl.test_replication_topology(topology_m2)
time.sleep(30)

user_dns_m1 = [user.dn for user in test_users_m1.list()]
user_dns_m2 = [user.dn for user in test_users_m2.list()]
> assert set(user_dns_m1) == set(user_dns_m2)
E AssertionError: assert {'uid=test_us...,dc=com', ...} == {'uid=test_us...,dc=com', ...}
E Extra items in the left set:
E 'uid=test_user_1112,cn=test_container,dc=example,dc=com'
E 'uid=test_user_1111,cn=test_container,dc=example,dc=com'
E 'uid=test_user_1117,cn=test_container,dc=example,dc=com'
E Full diff:
E {
E 'uid=test_user_1102,cn=test_container,dc=example,dc=com',...
E
E ...Full output truncated (24 lines hidden), use '-vv' to show

suites/replication/conflict_resolve_test.py:369: AssertionError
-------------------------------Captured log call--------------------------------
INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is NOT working (expect 67511e2e-c0d6-44a0-8f17-e2f64a02dbc4 / got description=9ccc5858-cfe6-4f5f-adca-7d1a512bd25d) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is NOT working (expect 67511e2e-c0d6-44a0-8f17-e2f64a02dbc4 / got description=9ccc5858-cfe6-4f5f-adca-7d1a512bd25d) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is NOT working (expect 67511e2e-c0d6-44a0-8f17-e2f64a02dbc4 / got description=9ccc5858-cfe6-4f5f-adca-7d1a512bd25d) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is NOT working (expect 67511e2e-c0d6-44a0-8f17-e2f64a02dbc4 / got description=9ccc5858-cfe6-4f5f-adca-7d1a512bd25d) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is NOT working (expect 67511e2e-c0d6-44a0-8f17-e2f64a02dbc4 / got description=9ccc5858-cfe6-4f5f-adca-7d1a512bd25d) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is working INFO  tests.suites.replication.conflict_resolve_test:conflict_resolve_test.py:285 Test add-del on M1 and add on M2 INFO  tests.suites.replication.conflict_resolve_test:conflict_resolve_test.py:301 Test add-mod on M1 and add on M2 INFO  tests.suites.replication.conflict_resolve_test:conflict_resolve_test.py:317 Test add-modrdn on M1 and add on M2 INFO  tests.suites.replication.conflict_resolve_test:conflict_resolve_test.py:333 Test multiple add, modrdn INFO  tests.suites.replication.conflict_resolve_test:conflict_resolve_test.py:341 Add - del on both masters INFO  tests.suites.replication.conflict_resolve_test:conflict_resolve_test.py:348 Test modrdn - modrdn INFO  tests.suites.replication.conflict_resolve_test:conflict_resolve_test.py:353 Test modrdn - del INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is NOT working (expect 18f4eeb1-f66c-499f-9fdc-872369c5c51c / got description=67511e2e-c0d6-44a0-8f17-e2f64a02dbc4) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is NOT working (expect 18f4eeb1-f66c-499f-9fdc-872369c5c51c / got description=67511e2e-c0d6-44a0-8f17-e2f64a02dbc4) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is NOT working (expect 18f4eeb1-f66c-499f-9fdc-872369c5c51c / got description=67511e2e-c0d6-44a0-8f17-e2f64a02dbc4) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is NOT working (expect 18f4eeb1-f66c-499f-9fdc-872369c5c51c / got description=67511e2e-c0d6-44a0-8f17-e2f64a02dbc4) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is NOT working (expect 18f4eeb1-f66c-499f-9fdc-872369c5c51c / got description=67511e2e-c0d6-44a0-8f17-e2f64a02dbc4) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 2f462887-4d64-4f57-9ccb-416437c1bee4 / got description=18f4eeb1-f66c-499f-9fdc-872369c5c51c) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is working

topology = <lib389.topologies.TopologyMain object at 0x7ff7f2fe6580>
request = <FixtureRequest for <Function test_sync_repl_cookie_add_del>>

def test_sync_repl_cookie_add_del(topology, request):
"""Test sync_repl cookie are progressing is an increasing order
when there add and del

:id: 83e11038-6ed0-4a5b-ac77-e44887ab11e3
:setup: Standalone Instance
:steps:
1.: enable retroCL
2.: configure retroCL to log nsuniqueid as targetUniqueId
3.: enable content_sync plugin
4.: enable automember
5.: create (2) groups. Few groups can help to reproduce the concurrent updates problem.
6.: configure automember to provision those groups with 'member'
7.: enable and configure memberof plugin
8.: enable plugin log level
9.: restart the server
10.: create a thread dedicated to run a sync repl client
11.: Create (3) users that will generate nested updates (automember/memberof)
12.: Delete (3) users
13.: stop sync repl client and collect the list of cookie.change_no
14.: check that cookies.change_no are in increasing order
:expectedresults:
1.: succeeds
2.: succeeds
3.: succeeds
4.: succeeds
5.: succeeds
6.: succeeds
7.: succeeds
8.: succeeds
9.: succeeds
10.: succeeds
11.: succeeds
12.: succeeds
13.: succeeds
14.: succeeds
"""
inst = topology[0]

# Enable/configure retroCL
plugin = RetroChangelogPlugin(inst)
plugin.disable()
plugin.enable()
plugin.set('nsslapd-attribute', 'nsuniqueid:targetuniqueid')

# Enable sync plugin
plugin = ContentSyncPlugin(inst)
plugin.enable()

# Enable automember
plugin = AutoMembershipPlugin(inst)
plugin.disable()
plugin.enable()

# Add the automember group
groups = Groups(inst, DEFAULT_SUFFIX)
group = []
for i in range(1,3):
group.append(groups.create(properties={'cn': 'group%d' % i}))

# Add the automember config entry
am_configs = AutoMembershipDefinitions(inst)
for g in group:
> am_config = am_configs.create(properties={'cn': 'config %s' % g.get_attr_val_utf8('cn'),
'autoMemberScope': DEFAULT_SUFFIX,
'autoMemberFilter': 'uid=*',
'autoMemberDefaultGroup': g.dn,
'autoMemberGroupingAttr': 'member:dn'})

suites/syncrepl_plugin/basic_test.py:343:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
/usr/local/lib/python3.8/site-packages/lib389/_mapped_object.py:1169: in create
return co.create(rdn, properties, self._basedn)
/usr/local/lib/python3.8/site-packages/lib389/_mapped_object.py:971: in create
return self._create(rdn, properties, basedn, ensure=False)
/usr/local/lib/python3.8/site-packages/lib389/_mapped_object.py:946: in _create
self._instance.add_ext_s(e, serverctrls=self._server_controls, clientctrls=self._client_controls, escapehatch='i am sure')
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:176: in inner
return f(ent.dn, ent.toTupleList(), *args[2:])
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:425: in add_ext_s
resp_type, resp_data, resp_msgid, resp_ctrls = self.result3(msgid,all=1,timeout=self.timeout)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:764: in result3
resp_type, resp_data, resp_msgid, decoded_resp_ctrls, retoid, retval = self.result4(
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:774: in result4
ldap_result = self._ldap_call(self._l.result4,msgid,all,timeout,add_ctrls,add_intermediates,add_extop)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:340: in _ldap_call
reraise(exc_type, exc_value, exc_traceback)
/usr/local/lib64/python3.8/site-packages/ldap/compat.py:46: in reraise
raise exc_value
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

self = <lib389.DirSrv object at 0x7ff7f2fe6dc0>
func = <built-in method result4 of LDAP object at 0x7ff7e9f9ec30>
args = (23, 1, -1, 0, 0, 0), kwargs = {}, diagnostic_message_success = None
exc_type = None, exc_value = None, exc_traceback = None

def _ldap_call(self,func,*args,**kwargs):
"""
Wrapper method mainly for serializing calls into OpenLDAP libs
and trace logs
"""
self._ldap_object_lock.acquire()
if __debug__:
if self._trace_level>=1:
self._trace_file.write('*** %s %s - %s\n%s\n' % (
repr(self),
self._uri,
'.'.join((self.__class__.__name__,func.__name__)),
pprint.pformat((args,kwargs))
))
if self._trace_level>=9:
traceback.print_stack(limit=self._trace_stack_limit,file=self._trace_file)
diagnostic_message_success = None
try:
try:
> result = func(*args,**kwargs)
E ldap.ALREADY_EXISTS: {'msgtype': 105, 'msgid': 23, 'result': 68, 'desc': 'Already exists', 'ctrls': []}

/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:324: ALREADY_EXISTS

topology = <lib389.topologies.TopologyMain object at 0x7ff7f2fe6580>
request = <FixtureRequest for <Function test_sync_repl_cookie_with_failure>>

def test_sync_repl_cookie_with_failure(topology, request):
"""Test sync_repl cookie are progressing is the right order
when there is a failure in nested updates

:id: e0103448-170e-4080-8f22-c34606447ce2
:setup: Standalone Instance
:steps:
1.: enable retroCL
2.: configure retroCL to log nsuniqueid as targetUniqueId
3.: enable content_sync plugin
4.: enable automember
5.: create (4) groups.
make group2 groupOfUniqueNames so the automember
will fail to add 'member' (uniqueMember expected)
6.: configure automember to provision those groups with 'member'
7.: enable and configure memberof plugin
8.: enable plugin log level
9.: restart the server
10.: create a thread dedicated to run a sync repl client
11.: Create a group that will be the only update received by sync repl client
12.: Create (9) users that will generate nested updates (automember/memberof)
13.: stop sync repl client and collect the list of cookie.change_no
14.: check that the list of cookie.change_no contains only the group 'step 11'
:expectedresults:
1.: succeeds
2.: succeeds
3.: succeeds
4.: succeeds
5.: succeeds
6.: succeeds
7.: succeeds
8.: succeeds
9.: succeeds
10.: succeeds
11.: succeeds
12.: Fails (expected)
13.: succeeds
14.: succeeds
"""
inst = topology[0]

# Enable/configure retroCL
plugin = RetroChangelogPlugin(inst)
plugin.disable()
plugin.enable()
plugin.set('nsslapd-attribute', 'nsuniqueid:targetuniqueid')

# Enable sync plugin
plugin = ContentSyncPlugin(inst)
plugin.enable()

# Enable automember
plugin = AutoMembershipPlugin(inst)
plugin.disable()
plugin.enable()

# Add the automember group
groups = Groups(inst, DEFAULT_SUFFIX)
group = []
for i in range(1,5):
> group.append(groups.create(properties={'cn': 'group%d' % i}))

suites/syncrepl_plugin/basic_test.py:470:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
/usr/local/lib/python3.8/site-packages/lib389/_mapped_object.py:1169: in create
return co.create(rdn, properties, self._basedn)
/usr/local/lib/python3.8/site-packages/lib389/_mapped_object.py:971: in create
return self._create(rdn, properties, basedn, ensure=False)
/usr/local/lib/python3.8/site-packages/lib389/_mapped_object.py:946: in _create
self._instance.add_ext_s(e, serverctrls=self._server_controls, clientctrls=self._client_controls, escapehatch='i am sure')
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:176: in inner
return f(ent.dn, ent.toTupleList(), *args[2:])
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:425: in add_ext_s
resp_type, resp_data, resp_msgid, resp_ctrls = self.result3(msgid,all=1,timeout=self.timeout)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:764: in result3
resp_type, resp_data, resp_msgid, decoded_resp_ctrls, retoid, retval = self.result4(
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:774: in result4
ldap_result = self._ldap_call(self._l.result4,msgid,all,timeout,add_ctrls,add_intermediates,add_extop)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:340: in _ldap_call
reraise(exc_type, exc_value, exc_traceback)
/usr/local/lib64/python3.8/site-packages/ldap/compat.py:46: in reraise
raise exc_value
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

self = <lib389.DirSrv object at 0x7ff7f2fe6dc0>
func = <built-in method result4 of LDAP object at 0x7ff7e9f9ec30>
args = (30, 1, -1, 0, 0, 0), kwargs = {}, diagnostic_message_success = None
exc_type = None, exc_value = None, exc_traceback = None

def _ldap_call(self,func,*args,**kwargs):
"""
Wrapper method mainly for serializing calls into OpenLDAP libs
and trace logs
"""
self._ldap_object_lock.acquire()
if __debug__:
if self._trace_level>=1:
self._trace_file.write('*** %s %s - %s\n%s\n' % (
repr(self),
self._uri,
'.'.join((self.__class__.__name__,func.__name__)),
pprint.pformat((args,kwargs))
))
if self._trace_level>=9:
traceback.print_stack(limit=self._trace_stack_limit,file=self._trace_file)
diagnostic_message_success = None
try:
try:
> result = func(*args,**kwargs)
E ldap.ALREADY_EXISTS: {'msgtype': 105, 'msgid': 30, 'result': 68, 'desc': 'Already exists', 'ctrls': []}

/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:324: ALREADY_EXISTS

self = <ValueSizeConstraint object, consts 16, 16>
value = b'v]\x8f\x81\t\r\x11\xeb\x86\x84\xc2cZ', idx = None

def __call__(self, value, idx=None):
if not self._values:
return

try:
> self._testValue(value, idx)

/usr/lib/python3.8/site-packages/pyasn1/type/constraint.py:32:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

self = <ValueSizeConstraint object, consts 16, 16>
value = b'v]\x8f\x81\t\r\x11\xeb\x86\x84\xc2cZ', idx = None

def _testValue(self, value, idx):
valueSize = len(value)
if valueSize < self.start or valueSize > self.stop:
> raise error.ValueConstraintError(value)
E pyasn1.type.error.ValueConstraintError: b'v]\x8f\x81\t\r\x11\xeb\x86\x84\xc2cZ'

/usr/lib/python3.8/site-packages/pyasn1/type/constraint.py:320: ValueConstraintError

During handling of the above exception, another exception occurred:

self = <[AttributeError("'SyncUUID' object has no attribute '_value'") raised in repr()] SyncUUID object at 0x7ff7eac04a90>
value = b'v]\x8f\x81\t\r\x11\xeb\x86\x84\xc2cZ'
kwargs = {'encoding': 'iso-8859-1', 'subtypeSpec': <ValueSizeConstraint object, consts 16, 16>, 'tagSet': <TagSet object, tags 0:0:4>}
exType = <class 'pyasn1.type.error.ValueConstraintError'>
exValue = ValueConstraintError("<ValueSizeConstraint object, consts 16, 16> failed at: ValueConstraintError(b'v]\\x8f\\x81\\t\\r\\x11\\xeb\\x86\\x84\\xc2cZ')")
exTb = <traceback object at 0x7ff7e95e4680>

def __init__(self, value=noValue, **kwargs):
Asn1Type.__init__(self, **kwargs)
if value is noValue:
value = self.defaultValue
else:
value = self.prettyIn(value)
try:
> self.subtypeSpec(value)

/usr/lib/python3.8/site-packages/pyasn1/type/base.py:269:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

self = <ValueSizeConstraint object, consts 16, 16>
value = b'v]\x8f\x81\t\r\x11\xeb\x86\x84\xc2cZ', idx = None

def __call__(self, value, idx=None):
if not self._values:
return

try:
self._testValue(value, idx)

except error.ValueConstraintError:
> raise error.ValueConstraintError(
'%s failed at: %r' % (self, sys.exc_info()[1])
)
E pyasn1.type.error.ValueConstraintError: <ValueSizeConstraint object, consts 16, 16> failed at: ValueConstraintError(b'v]\x8f\x81\t\r\x11\xeb\x86\x84\xc2cZ')

/usr/lib/python3.8/site-packages/pyasn1/type/constraint.py:35: ValueConstraintError

During handling of the above exception, another exception occurred:

topology = <lib389.topologies.TopologyMain object at 0x7ff7e9a82640>

@pytest.mark.skipif(ds_is_older('1.4.4.0'), reason="Sync repl does not support openldap compat in older versions")
def test_syncrepl_openldap(topology):
""" Test basic functionality of the openldap syncrepl
compatability handler.

:id: 03039178-2cc6-40bd-b32c-7d6de108828b

:setup: Standalone instance

:steps:
1. Enable Retro Changelog
2. Enable Syncrepl
3. Run the syncstate test to check refresh, add, delete, mod.

:expectedresults:
1. Success
1. Success
1. Success
"""
st = topology.standalone
# Enable RetroChangelog.
rcl = RetroChangelogPlugin(st)
rcl.enable()
# Set the default targetid
rcl.replace('nsslapd-attribute', 'nsuniqueid:targetUniqueId')
# Enable sync repl
csp = ContentSyncPlugin(st)
csp.enable()
# Restart DS
st.restart()
# log.error("+++++++++++")
# time.sleep(60)
# Setup the syncer
sync = ISyncRepl(st, openldap=True)
# Run the checks
> syncstate_assert(st, sync)

suites/syncrepl_plugin/openldap_test.py:63:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
suites/syncrepl_plugin/__init__.py:154: in syncstate_assert
sync.syncrepl_complete()
suites/syncrepl_plugin/__init__.py:69: in syncrepl_complete
while super().syncrepl_poll(msgid=self.msgid) is True:
/usr/local/lib64/python3.8/site-packages/ldap/syncrepl.py:449: in syncrepl_poll
sim = SyncInfoMessage(resp)
/usr/local/lib64/python3.8/site-packages/ldap/syncrepl.py:311: in __init__
d = decoder.decode(encodedMessage, asn1Spec=SyncInfoValue())
/usr/lib/python3.8/site-packages/pyasn1/codec/ber/decoder.py:1581: in __call__
value, substrate = concreteDecoder.valueDecoder(
/usr/lib/python3.8/site-packages/pyasn1/codec/ber/decoder.py:1006: in valueDecoder
component, head = decodeFun(
/usr/lib/python3.8/site-packages/pyasn1/codec/ber/decoder.py:1581: in __call__
value, substrate = concreteDecoder.valueDecoder(
/usr/lib/python3.8/site-packages/pyasn1/codec/ber/decoder.py:609: in valueDecoder
component, head = decodeFun(head, componentType, **options)
/usr/lib/python3.8/site-packages/pyasn1/codec/ber/decoder.py:1581: in __call__
value, substrate = concreteDecoder.valueDecoder(
/usr/lib/python3.8/site-packages/pyasn1/codec/ber/decoder.py:728: in valueDecoder
component, head = decodeFun(head, componentType, **options)
/usr/lib/python3.8/site-packages/pyasn1/codec/ber/decoder.py:1581: in __call__
value, substrate = concreteDecoder.valueDecoder(
/usr/lib/python3.8/site-packages/pyasn1/codec/ber/decoder.py:244: in valueDecoder
return self._createComponent(asn1Spec, tagSet, head, **options), tail
/usr/lib/python3.8/site-packages/pyasn1/codec/ber/decoder.py:55: in _createComponent
return asn1Spec.clone(value)
/usr/lib/python3.8/site-packages/pyasn1/type/base.py:376: in clone
return self.__class__(value, **initializers)
/usr/lib/python3.8/site-packages/pyasn1/type/univ.py:837: in __init__
base.SimpleAsn1Type.__init__(self, value, **kwargs)
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

self = <[AttributeError("'SyncUUID' object has no attribute '_value'") raised in repr()] SyncUUID object at 0x7ff7eac04a90>
value = b'v]\x8f\x81\t\r\x11\xeb\x86\x84\xc2cZ'
kwargs = {'encoding': 'iso-8859-1', 'subtypeSpec': <ValueSizeConstraint object, consts 16, 16>, 'tagSet': <TagSet object, tags 0:0:4>}
exType = <class 'pyasn1.type.error.ValueConstraintError'>
exValue = ValueConstraintError("<ValueSizeConstraint object, consts 16, 16> failed at: ValueConstraintError(b'v]\\x8f\\x81\\t\\r\\x11\\xeb\\x86\\x84\\xc2cZ')")
exTb = <traceback object at 0x7ff7e95e4680>

def __init__(self, value=noValue, **kwargs):
Asn1Type.__init__(self, **kwargs)
if value is noValue:
value = self.defaultValue
else:
value = self.prettyIn(value)
try:
self.subtypeSpec(value)

except error.PyAsn1Error:
exType, exValue, exTb = sys.exc_info()
> raise exType('%s at %s' % (exValue, self.__class__.__name__))
E pyasn1.type.error.ValueConstraintError: <ValueSizeConstraint object, consts 16, 16> failed at: ValueConstraintError(b'v]\x8f\x81\t\r\x11\xeb\x86\x84\xc2cZ') at SyncUUID

/usr/lib/python3.8/site-packages/pyasn1/type/base.py:273: ValueConstraintError
-------------------------------Captured log setup-------------------------------
INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 38901, 'ldap-secureport': 63601, 'server-id': 'standalone1', 'suffix': 'dc=example,dc=com'} was created.

topology_st = <lib389.topologies.TopologyMain object at 0x7ff7e9c7f580>

def test_ticket47781(topology_st):
"""
Testing for a deadlock after doing an online import of an LDIF with
replication data. The replication agreement should be invalid.
"""

log.info('Testing Ticket 47781 - Testing for deadlock after importing LDIF with replication data')

master = topology_st.standalone
repl = ReplicationManager(DEFAULT_SUFFIX)
repl.create_first_master(master)

properties = {RA_NAME: r'meTo_$host:$port',
RA_BINDDN: defaultProperties[REPLICATION_BIND_DN],
RA_BINDPW: defaultProperties[REPLICATION_BIND_PW],
RA_METHOD: defaultProperties[REPLICATION_BIND_METHOD],
RA_TRANSPORT_PROT: defaultProperties[REPLICATION_TRANSPORT]}
# The agreement should point to a server that does NOT exist (invalid port)
repl_agreement = master.agreement.create(suffix=DEFAULT_SUFFIX,
host=master.host,
port=5555,
properties=properties)

#
# add two entries
#
log.info('Adding two entries...')

master.add_s(Entry(('cn=entry1,dc=example,dc=com', {
'objectclass': 'top person'.split(),
'sn': 'user',
'cn': 'entry1'})))

master.add_s(Entry(('cn=entry2,dc=example,dc=com', {
'objectclass': 'top person'.split(),
'sn': 'user',
'cn': 'entry2'})))

#
# export the replication ldif
#
log.info('Exporting replication ldif...')
args = {EXPORT_REPL_INFO: True}
exportTask = Tasks(master)
exportTask.exportLDIF(DEFAULT_SUFFIX, None, "/tmp/export.ldif", args)

#
# Restart the server
#
log.info('Restarting server...')
master.stop()
master.start()

#
# Import the ldif
#
log.info('Import replication LDIF file...')
importTask = Tasks(master)
args = {TASK_WAIT: True}
> importTask.importLDIF(DEFAULT_SUFFIX, None, "/tmp/export.ldif", args)

tickets/ticket47781_test.py:85:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

self = <lib389.tasks.Tasks object at 0x7ff7f2ffb430>
suffix = 'dc=example,dc=com', benamebase = None, input_file = '/tmp/export.ldif'
args = {'wait': True}

def importLDIF(self, suffix=None, benamebase=None, input_file=None,
args=None):
'''
Import from a LDIF format a given 'suffix' (or 'benamebase' that stores
that suffix). It uses an internal task to acheive this request.

If 'suffix' and 'benamebase' are specified, it uses 'benamebase' first
else 'suffix'.
If both 'suffix' and 'benamebase' are missing it raise ValueError

'input_file' is the ldif input file

@param suffix - suffix of the backend
@param benamebase - 'commonname'/'cn' of the backend (e.g. 'userRoot')
@param ldif_input - file that will contain the entries in LDIF format
to import
@param args - is a dictionary that contains modifier of the import task
wait: True/[False] - If True, 'export' waits for the completion
of the task before to return

@return None

@raise ValueError

'''
if self.conn.state != DIRSRV_STATE_ONLINE:
raise ValueError("Invalid Server State %s! Must be online" % self.conn.state)

# Checking the parameters
if not benamebase and not suffix:
raise ValueError("Specify either bename or suffix")

if not input_file:
raise ValueError("input_file is mandatory")

if not os.path.exists(input_file):
> raise ValueError("Import file (%s) does not exist" % input_file)
E ValueError: Import file (/tmp/export.ldif) does not exist

/usr/local/lib/python3.8/site-packages/lib389/tasks.py:470: ValueError
-------------------------------Captured log setup-------------------------------
INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 38901, 'ldap-secureport': 63601, 'server-id': 'standalone1', 'suffix': 'dc=example,dc=com'} was created.
-------------------------------Captured log call--------------------------------
INFO  lib389:tasks.py:564 Export task export_10072020_223356 for file /tmp/export.ldif completed successfully

topology_st = <lib389.topologies.TopologyMain object at 0x7ff7ea153d90>

def test_ticket47973_case(topology_st):
log.info('Testing Ticket 47973 (case) - Test the cases in the original schema are preserved.')

log.info('case 1 - Test the cases in the original schema are preserved.')

tsfile = topology_st.standalone.schemadir + '/98test.ldif'
tsfd = open(tsfile, "w")
Mozattr0 = "MoZiLLaaTTRiBuTe"
testschema = "dn: cn=schema\nattributetypes: ( 8.9.10.11.12.13.14 NAME '" + Mozattr0 + "' SYNTAX 1.3.6.1.4.1.1466.115.121.1.15 X-ORIGIN 'Mozilla Dummy Schema' )\nobjectclasses: ( 1.2.3.4.5.6.7 NAME 'MozillaObject' SUP top MUST ( objectclass $ cn ) MAY ( " + Mozattr0 + " ) X-ORIGIN 'user defined' )"
tsfd.write(testschema)
tsfd.close()

try:
# run the schema reload task with the default schemadir
topology_st.standalone.tasks.schemaReload(schemadir=topology_st.standalone.schemadir,
args={TASK_WAIT: False})
except ValueError:
log.error('Schema Reload task failed.')
assert False

time.sleep(5)

try:
schemaentry = topology_st.standalone.search_s("cn=schema", ldap.SCOPE_BASE,
'objectclass=top',
["objectclasses"])
oclist = schemaentry[0].data.get("objectclasses")
except ldap.LDAPError as e:
log.error('Failed to get schema entry: error (%s)' % e.args[0]['desc'])
raise e

found = 0
for oc in oclist:
log.info('OC: %s' % oc)
moz = re.findall(Mozattr0, oc.decode('utf-8'))
if moz:
found = 1
log.info('case 1: %s is in the objectclasses list -- PASS' % Mozattr0)

if found == 0:
log.error('case 1: %s is not in the objectclasses list -- FAILURE' % Mozattr0)
> assert False
E assert False

tickets/ticket47973_test.py:156: AssertionError
-------------------------------Captured log call--------------------------------
INFO  lib389:tasks.py:1166 Schema Reload task (task-10072020_224250) completed successfully ERROR  tests.tickets.ticket47973_test:ticket47973_test.py:155 case 1: MoZiLLaaTTRiBuTe is not in the objectclasses list -- FAILURE

topology_m2 = <lib389.topologies.TopologyMain object at 0x7ff7f2732d60>

def test_ticket47988_init(topology_m2):
"""
It adds
- Objectclass with MAY 'member'
- an entry ('bind_entry') with which we bind to test the 'SELFDN' operation
It deletes the anonymous aci

"""

_header(topology_m2, 'test_ticket47988_init')

# enable acl error logging
mod = [(ldap.MOD_REPLACE, 'nsslapd-errorlog-level', ensure_bytes(str(8192)))] # REPL
topology_m2.ms["master1"].modify_s(DN_CONFIG, mod)
topology_m2.ms["master2"].modify_s(DN_CONFIG, mod)

mod = [(ldap.MOD_REPLACE, 'nsslapd-accesslog-level', ensure_bytes(str(260)))] # Internal op
topology_m2.ms["master1"].modify_s(DN_CONFIG, mod)
topology_m2.ms["master2"].modify_s(DN_CONFIG, mod)

# add dummy entries
for cpt in range(MAX_OTHERS):
name = "%s%d" % (OTHER_NAME, cpt)
topology_m2.ms["master1"].add_s(Entry(("cn=%s,%s" % (name, SUFFIX), {
'objectclass': "top person".split(),
'sn': name,
'cn': name})))

# check that entry 0 is replicated before
loop = 0
entryDN = "cn=%s0,%s" % (OTHER_NAME, SUFFIX)
while loop <= 10:
try:
ent = topology_m2.ms["master2"].getEntry(entryDN, ldap.SCOPE_BASE, "(objectclass=*)", ['telephonenumber'])
break
except ldap.NO_SUCH_OBJECT:
time.sleep(1)
loop += 1
assert (loop <= 10)

topology_m2.ms["master1"].stop(timeout=10)
topology_m2.ms["master2"].stop(timeout=10)

# install the specific schema M1: ipa3.3, M2: ipa4.1
schema_file = os.path.join(topology_m2.ms["master1"].getDir(__file__, DATA_DIR), "ticket47988/schema_ipa3.3.tar.gz")
_install_schema(topology_m2.ms["master1"], schema_file)
schema_file = os.path.join(topology_m2.ms["master1"].getDir(__file__, DATA_DIR), "ticket47988/schema_ipa4.1.tar.gz")
_install_schema(topology_m2.ms["master2"], schema_file)

> topology_m2.ms["master1"].start(timeout=10)

/export/tests/tickets/ticket47988_test.py:157:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:1135: in start
subprocess.check_output(["systemctl", "start", "dirsrv@%s" % self.serverid], stderr=subprocess.STDOUT)
/usr/lib64/python3.8/subprocess.py:411: in check_output
return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

input = None, capture_output = False, timeout = None, check = True
popenargs = (['systemctl', 'start', 'dirsrv@master1'],)
kwargs = {'stderr': -2, 'stdout': -1}
process = <subprocess.Popen object at 0x7ff7f0d8c8b0>
stdout = b'Job for dirsrv@master1.service failed because the control process exited with error code.\nSee "systemctl status dirsrv@master1.service" and "journalctl -xe" for details.\n'
stderr = None, retcode = 1

def run(*popenargs,
input=None, capture_output=False, timeout=None, check=False, **kwargs):
"""Run command with arguments and return a CompletedProcess instance.

The returned instance will have attributes args, returncode, stdout and
stderr. By default, stdout and stderr are not captured, and those attributes
will be None. Pass stdout=PIPE and/or stderr=PIPE in order to capture them.

If check is True and the exit code was non-zero, it raises a
CalledProcessError. The CalledProcessError object will have the return code
in the returncode attribute, and output & stderr attributes if those streams
were captured.

If timeout is given, and the process takes too long, a TimeoutExpired
exception will be raised.

There is an optional argument "input", allowing you to
pass bytes or a string to the subprocess's stdin. If you use this argument
you may not also use the Popen constructor's "stdin" argument, as
it will be used internally.

By default, all communication is in bytes, and therefore any "input" should
be bytes, and the stdout and stderr will be bytes. If in text mode, any
"input" should be a string, and stdout and stderr will be strings decoded
according to locale encoding, or by "encoding" if set. Text mode is
triggered by setting any of text, encoding, errors or universal_newlines.

The other arguments are the same as for the Popen constructor.
"""
if input is not None:
if kwargs.get('stdin') is not None:
raise ValueError('stdin and input arguments may not both be used.')
kwargs['stdin'] = PIPE

if capture_output:
if kwargs.get('stdout') is not None or kwargs.get('stderr') is not None:
raise ValueError('stdout and stderr arguments may not be used '
'with capture_output.')
kwargs['stdout'] = PIPE
kwargs['stderr'] = PIPE

with Popen(*popenargs, **kwargs) as process:
try:
stdout, stderr = process.communicate(input, timeout=timeout)
except TimeoutExpired as exc:
process.kill()
if _mswindows:
# Windows accumulates the output in a single blocking
# read() call run on child threads, with the timeout
# being done in a join() on those threads. communicate()
# _after_ kill() is required to collect that and add it
# to the exception.
exc.stdout, exc.stderr = process.communicate()
else:
# POSIX _communicate already populated the output so
# far into the TimeoutExpired exception.
process.wait()
raise
except: # Including KeyboardInterrupt, communicate handled that.
process.kill()
# We don't call process.wait() as .__exit__ does that for us.
raise
retcode = process.poll()
if check and retcode:
> raise CalledProcessError(retcode, process.args,
output=stdout, stderr=stderr)
E subprocess.CalledProcessError: Command '['systemctl', 'start', 'dirsrv@master1']' returned non-zero exit status 1.

/usr/lib64/python3.8/subprocess.py:512: CalledProcessError
-------------------------------Captured log setup-------------------------------
INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 39001, 'ldap-secureport': 63701, 'server-id': 'master1', 'suffix': 'dc=example,dc=com'} was created. INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 39002, 'ldap-secureport': 63702, 'server-id': 'master2', 'suffix': 'dc=example,dc=com'} was created. INFO  lib389.topologies:topologies.py:148 Creating replication topology. INFO  lib389.topologies:topologies.py:162 Joining master master2 to master1 ... INFO  lib389.replica:replica.py:2068 SUCCESS: bootstrap to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 completed INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is was created INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is was created INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is NOT working (expect 0d4e61e1-d4d7-4471-9c24-a6cfd7a15112 / got description=None) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect f5684dee-d386-4b7a-8ef9-7ef1c1ec1284 / got description=0d4e61e1-d4d7-4471-9c24-a6cfd7a15112) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is working INFO  lib389.replica:replica.py:2137 SUCCESS: joined master from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 INFO  lib389.topologies:topologies.py:170 Ensuring master master1 to master2 ... INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 already exists INFO  lib389.topologies:topologies.py:170 Ensuring master master2 to master1 ... INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 already exists
-------------------------------Captured log call--------------------------------
INFO  lib389:ticket47988_test.py:64 ############################################### INFO  lib389:ticket47988_test.py:65 ####### INFO  lib389:ticket47988_test.py:66 ####### test_ticket47988_init INFO  lib389:ticket47988_test.py:67 ####### INFO  lib389:ticket47988_test.py:68 ################################################### INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/02common.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/50ns-admin.ldif INFO  lib389:ticket47988_test.py:98 replace /etc/dirsrv/slapd-master1/schema/99user.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/60nss-ldap.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/60autofs.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/50ns-web.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/60samba.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/10dna-plugin.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/05rfc4523.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/60basev2.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/10automember-plugin.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/05rfc2927.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/10mep-plugin.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/60ipadns.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/10rfc2307.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/50ns-mail.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/05rfc4524.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/60trust.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/60ipaconfig.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/50ns-directory.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/60eduperson.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/60mozilla.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/65ipasudo.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/60rfc3712.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/60rfc2739.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/50ns-value.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/60acctpolicy.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/01core389.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/60sabayon.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/60pam-plugin.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/00core.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/25java-object.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/60sudo.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/70ipaotp.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/60pureftpd.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/61kerberos-ipav3.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/60kerberos.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/60basev3.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/06inetorgperson.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/30ns-common.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/28pilot.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/20subscriber.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/50ns-certificate.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master1/schema/60posix-winsync-plugin.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/02common.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/50ns-admin.ldif INFO  lib389:ticket47988_test.py:98 replace /etc/dirsrv/slapd-master2/schema/99user.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/60nss-ldap.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/60autofs.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/50ns-web.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/60samba.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/10dna-plugin.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/05rfc4523.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/60basev2.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/10automember-plugin.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/05rfc2927.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/10mep-plugin.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/60ipadns.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/10rfc2307.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/50ns-mail.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/05rfc4524.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/60trust.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/60ipaconfig.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/50ns-directory.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/60eduperson.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/60mozilla.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/65ipasudo.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/60rfc3712.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/60rfc2739.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/50ns-value.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/60acctpolicy.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/01core389.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/60sabayon.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/60pam-plugin.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/00core.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/25java-object.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/60sudo.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/70ipaotp.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/60pureftpd.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/61kerberos-ipav3.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/60kerberos.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/60basev3.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/06inetorgperson.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/30ns-common.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/28pilot.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/20subscriber.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/50ns-certificate.ldif INFO  lib389:ticket47988_test.py:102 add /etc/dirsrv/slapd-master2/schema/60posix-winsync-plugin.ldif

topology_m2 = <lib389.topologies.TopologyMain object at 0x7ff7f2732d60>

def test_ticket47988_1(topology_m2):
'''
Check that replication is working and pause replication M2->M1
'''
_header(topology_m2, 'test_ticket47988_1')

topology_m2.ms["master1"].log.debug("\n\nCheck that replication is working and pause replication M2->M1\n")
> _do_update_entry(supplier=topology_m2.ms["master2"], consumer=topology_m2.ms["master1"], attempts=5)

/export/tests/tickets/ticket47988_test.py:234:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
/export/tests/tickets/ticket47988_test.py:184: in _do_update_entry
supplier.modify_s(entryDN, mod)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:640: in modify_s
return self.modify_ext_s(dn,modlist,None,None)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:613: in modify_ext_s
resp_type, resp_data, resp_msgid, resp_ctrls = self.result3(msgid,all=1,timeout=self.timeout)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:764: in result3
resp_type, resp_data, resp_msgid, decoded_resp_ctrls, retoid, retval = self.result4(
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:774: in result4
ldap_result = self._ldap_call(self._l.result4,msgid,all,timeout,add_ctrls,add_intermediates,add_extop)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:340: in _ldap_call
reraise(exc_type, exc_value, exc_traceback)
/usr/local/lib64/python3.8/site-packages/ldap/compat.py:46: in reraise
raise exc_value
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

self = <lib389.DirSrv object at 0x7ff7f0da9880>
func = <built-in method result4 of LDAP object at 0x7ff7f0d812a0>
args = (26, 1, -1, 0, 0, 0), kwargs = {}, diagnostic_message_success = None
exc_type = None, exc_value = None, exc_traceback = None

def _ldap_call(self,func,*args,**kwargs):
"""
Wrapper method mainly for serializing calls into OpenLDAP libs
and trace logs
"""
self._ldap_object_lock.acquire()
if __debug__:
if self._trace_level>=1:
self._trace_file.write('*** %s %s - %s\n%s\n' % (
repr(self),
self._uri,
'.'.join((self.__class__.__name__,func.__name__)),
pprint.pformat((args,kwargs))
))
if self._trace_level>=9:
traceback.print_stack(limit=self._trace_stack_limit,file=self._trace_file)
diagnostic_message_success = None
try:
try:
> result = func(*args,**kwargs)
E ldap.SERVER_DOWN: {'result': -1, 'desc': "Can't contact LDAP server", 'ctrls': []}

/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:324: SERVER_DOWN
-------------------------------Captured log call--------------------------------
INFO  lib389:ticket47988_test.py:64 ############################################### INFO  lib389:ticket47988_test.py:65 ####### INFO  lib389:ticket47988_test.py:66 ####### test_ticket47988_1 INFO  lib389:ticket47988_test.py:67 ####### INFO  lib389:ticket47988_test.py:68 ###################################################

topology_m2 = <lib389.topologies.TopologyMain object at 0x7ff7f2732d60>

def test_ticket47988_2(topology_m2):
'''
Update M1 schema and trigger update M1->M2
So M1 should learn new/extended definitions that are in M2 schema
'''
_header(topology_m2, 'test_ticket47988_2')

topology_m2.ms["master1"].log.debug("\n\nUpdate M1 schema and an entry on M1\n")
> master1_schema_csn = topology_m2.ms["master1"].schema.get_schema_csn()

/export/tests/tickets/ticket47988_test.py:246:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
/usr/local/lib/python3.8/site-packages/lib389/schema.py:604: in get_schema_csn
ents = self.conn.search_s(DN_SCHEMA, ldap.SCOPE_BASE,
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:870: in search_s
return self.search_ext_s(base,scope,filterstr,attrlist,attrsonly,None,None,timeout=self.timeout)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:864: in search_ext_s
return self.result(msgid,all=1,timeout=timeout)[1]
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:148: in inner
objtype, data = f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:756: in result
resp_type, resp_data, resp_msgid = self.result2(msgid,all,timeout)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:760: in result2
resp_type, resp_data, resp_msgid, resp_ctrls = self.result3(msgid,all,timeout)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:764: in result3
resp_type, resp_data, resp_msgid, decoded_resp_ctrls, retoid, retval = self.result4(
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:774: in result4
ldap_result = self._ldap_call(self._l.result4,msgid,all,timeout,add_ctrls,add_intermediates,add_extop)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:340: in _ldap_call
reraise(exc_type, exc_value, exc_traceback)
/usr/local/lib64/python3.8/site-packages/ldap/compat.py:46: in reraise
raise exc_value
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

self = <lib389.DirSrv object at 0x7ff7f27328b0>
func = <built-in method result4 of LDAP object at 0x7ff7f0d89f90>
args = (62, 1, -1, 0, 0, 0), kwargs = {}, diagnostic_message_success = None
exc_type = None, exc_value = None, exc_traceback = None

def _ldap_call(self,func,*args,**kwargs):
"""
Wrapper method mainly for serializing calls into OpenLDAP libs
and trace logs
"""
self._ldap_object_lock.acquire()
if __debug__:
if self._trace_level>=1:
self._trace_file.write('*** %s %s - %s\n%s\n' % (
repr(self),
self._uri,
'.'.join((self.__class__.__name__,func.__name__)),
pprint.pformat((args,kwargs))
))
if self._trace_level>=9:
traceback.print_stack(limit=self._trace_stack_limit,file=self._trace_file)
diagnostic_message_success = None
try:
try:
> result = func(*args,**kwargs)
E ldap.SERVER_DOWN: {'result': -1, 'desc': "Can't contact LDAP server", 'ctrls': []}

/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:324: SERVER_DOWN
-------------------------------Captured log call--------------------------------
INFO  lib389:ticket47988_test.py:64 ############################################### INFO  lib389:ticket47988_test.py:65 ####### INFO  lib389:ticket47988_test.py:66 ####### test_ticket47988_2 INFO  lib389:ticket47988_test.py:67 ####### INFO  lib389:ticket47988_test.py:68 ###################################################

topology_m2 = <lib389.topologies.TopologyMain object at 0x7ff7f2732d60>

def test_ticket47988_3(topology_m2):
'''
Resume replication M2->M1 and check replication is still working
'''
_header(topology_m2, 'test_ticket47988_3')

> _resume_M2_to_M1(topology_m2)

/export/tests/tickets/ticket47988_test.py:283:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
/export/tests/tickets/ticket47988_test.py:222: in _resume_M2_to_M1
ents = topology_m2.ms["master2"].agreement.list(suffix=SUFFIX)
/usr/local/lib/python3.8/site-packages/lib389/agreement.py:905: in list
replica_entries = self.conn.replica.list(suffix)
/usr/local/lib/python3.8/site-packages/lib389/replica.py:178: in list
ents = self.conn.search_s(base, ldap.SCOPE_SUBTREE, filtr)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:870: in search_s
return self.search_ext_s(base,scope,filterstr,attrlist,attrsonly,None,None,timeout=self.timeout)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:863: in search_ext_s
msgid = self.search_ext(base,scope,filterstr,attrlist,attrsonly,serverctrls,clientctrls,timeout,sizelimit)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:853: in search_ext
return self._ldap_call(
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:340: in _ldap_call
reraise(exc_type, exc_value, exc_traceback)
/usr/local/lib64/python3.8/site-packages/ldap/compat.py:46: in reraise
raise exc_value
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

self = <lib389.DirSrv object at 0x7ff7f0da9880>
func = <built-in method search_ext of LDAP object at 0x7ff7f0d812a0>
args = ('cn=mapping tree,cn=config', 2, '(&(objectclass=nsds5Replica)(nsDS5ReplicaRoot=dc=example,dc=com))', None, 0, None, ...)
kwargs = {}, diagnostic_message_success = None, exc_type = None
exc_value = None, exc_traceback = None

def _ldap_call(self,func,*args,**kwargs):
"""
Wrapper method mainly for serializing calls into OpenLDAP libs
and trace logs
"""
self._ldap_object_lock.acquire()
if __debug__:
if self._trace_level>=1:
self._trace_file.write('*** %s %s - %s\n%s\n' % (
repr(self),
self._uri,
'.'.join((self.__class__.__name__,func.__name__)),
pprint.pformat((args,kwargs))
))
if self._trace_level>=9:
traceback.print_stack(limit=self._trace_stack_limit,file=self._trace_file)
diagnostic_message_success = None
try:
try:
> result = func(*args,**kwargs)
E ldap.SERVER_DOWN: {'result': -1, 'desc': "Can't contact LDAP server", 'ctrls': []}

/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:324: SERVER_DOWN
-------------------------------Captured log call--------------------------------
INFO  lib389:ticket47988_test.py:64 ############################################### INFO  lib389:ticket47988_test.py:65 ####### INFO  lib389:ticket47988_test.py:66 ####### test_ticket47988_3 INFO  lib389:ticket47988_test.py:67 ####### INFO  lib389:ticket47988_test.py:68 ################################################### INFO  lib389:ticket47988_test.py:221 ######################### resume RA M2->M1 ######################

topology_m2 = <lib389.topologies.TopologyMain object at 0x7ff7f2732d60>

def test_ticket47988_4(topology_m2):
'''
Check schemaCSN is identical on both server
And save the nsschemaCSN to later check they do not change unexpectedly
'''
_header(topology_m2, 'test_ticket47988_4')

> master1_schema_csn = topology_m2.ms["master1"].schema.get_schema_csn()

/export/tests/tickets/ticket47988_test.py:295:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
/usr/local/lib/python3.8/site-packages/lib389/schema.py:604: in get_schema_csn
ents = self.conn.search_s(DN_SCHEMA, ldap.SCOPE_BASE,
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:870: in search_s
return self.search_ext_s(base,scope,filterstr,attrlist,attrsonly,None,None,timeout=self.timeout)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:863: in search_ext_s
msgid = self.search_ext(base,scope,filterstr,attrlist,attrsonly,serverctrls,clientctrls,timeout,sizelimit)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:853: in search_ext
return self._ldap_call(
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:340: in _ldap_call
reraise(exc_type, exc_value, exc_traceback)
/usr/local/lib64/python3.8/site-packages/ldap/compat.py:46: in reraise
raise exc_value
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

self = <lib389.DirSrv object at 0x7ff7f27328b0>
func = <built-in method search_ext of LDAP object at 0x7ff7f0d89f90>
args = ('cn=schema', 0, 'objectclass=*', ['nsSchemaCSN'], 0, None, ...)
kwargs = {}, diagnostic_message_success = None, exc_type = None
exc_value = None, exc_traceback = None

def _ldap_call(self,func,*args,**kwargs):
"""
Wrapper method mainly for serializing calls into OpenLDAP libs
and trace logs
"""
self._ldap_object_lock.acquire()
if __debug__:
if self._trace_level>=1:
self._trace_file.write('*** %s %s - %s\n%s\n' % (
repr(self),
self._uri,
'.'.join((self.__class__.__name__,func.__name__)),
pprint.pformat((args,kwargs))
))
if self._trace_level>=9:
traceback.print_stack(limit=self._trace_stack_limit,file=self._trace_file)
diagnostic_message_success = None
try:
try:
> result = func(*args,**kwargs)
E ldap.SERVER_DOWN: {'result': -1, 'desc': "Can't contact LDAP server", 'ctrls': []}

/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:324: SERVER_DOWN
-------------------------------Captured log call--------------------------------
INFO  lib389:ticket47988_test.py:64 ############################################### INFO  lib389:ticket47988_test.py:65 ####### INFO  lib389:ticket47988_test.py:66 ####### test_ticket47988_4 INFO  lib389:ticket47988_test.py:67 ####### INFO  lib389:ticket47988_test.py:68 ###################################################

topology_m2 = <lib389.topologies.TopologyMain object at 0x7ff7f2732d60>

def test_ticket47988_5(topology_m2):
'''
Check schemaCSN do not change unexpectedly
'''
_header(topology_m2, 'test_ticket47988_5')

> _do_update_entry(supplier=topology_m2.ms["master1"], consumer=topology_m2.ms["master2"], attempts=5)

/export/tests/tickets/ticket47988_test.py:313:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
/export/tests/tickets/ticket47988_test.py:184: in _do_update_entry
supplier.modify_s(entryDN, mod)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:640: in modify_s
return self.modify_ext_s(dn,modlist,None,None)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:612: in modify_ext_s
msgid = self.modify_ext(dn,modlist,serverctrls,clientctrls)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:609: in modify_ext
return self._ldap_call(self._l.modify_ext,dn,modlist,RequestControlTuples(serverctrls),RequestControlTuples(clientctrls))
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:340: in _ldap_call
reraise(exc_type, exc_value, exc_traceback)
/usr/local/lib64/python3.8/site-packages/ldap/compat.py:46: in reraise
raise exc_value
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

self = <lib389.DirSrv object at 0x7ff7f27328b0>
func = <built-in method modify_ext of LDAP object at 0x7ff7f0d89f90>
args = ('cn=other_entry0,dc=example,dc=com', [(2, 'telephonenumber', b'108')], None, None)
kwargs = {}, diagnostic_message_success = None, exc_type = None
exc_value = None, exc_traceback = None

def _ldap_call(self,func,*args,**kwargs):
"""
Wrapper method mainly for serializing calls into OpenLDAP libs
and trace logs
"""
self._ldap_object_lock.acquire()
if __debug__:
if self._trace_level>=1:
self._trace_file.write('*** %s %s - %s\n%s\n' % (
repr(self),
self._uri,
'.'.join((self.__class__.__name__,func.__name__)),
pprint.pformat((args,kwargs))
))
if self._trace_level>=9:
traceback.print_stack(limit=self._trace_stack_limit,file=self._trace_file)
diagnostic_message_success = None
try:
try:
> result = func(*args,**kwargs)
E ldap.SERVER_DOWN: {'result': -1, 'desc': "Can't contact LDAP server", 'ctrls': []}

/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:324: SERVER_DOWN
-------------------------------Captured log call--------------------------------
INFO  lib389:ticket47988_test.py:64 ############################################### INFO  lib389:ticket47988_test.py:65 ####### INFO  lib389:ticket47988_test.py:66 ####### test_ticket47988_5 INFO  lib389:ticket47988_test.py:67 ####### INFO  lib389:ticket47988_test.py:68 ###################################################

topology_m2 = <lib389.topologies.TopologyMain object at 0x7ff7f2732d60>

def test_ticket47988_6(topology_m2):
'''
Update M1 schema and trigger update M2->M1
So M2 should learn new/extended definitions that are in M1 schema
'''

_header(topology_m2, 'test_ticket47988_6')

topology_m2.ms["master1"].log.debug("\n\nUpdate M1 schema and an entry on M1\n")
> master1_schema_csn = topology_m2.ms["master1"].schema.get_schema_csn()

/export/tests/tickets/ticket47988_test.py:336:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
/usr/local/lib/python3.8/site-packages/lib389/schema.py:604: in get_schema_csn
ents = self.conn.search_s(DN_SCHEMA, ldap.SCOPE_BASE,
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:870: in search_s
return self.search_ext_s(base,scope,filterstr,attrlist,attrsonly,None,None,timeout=self.timeout)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:863: in search_ext_s
msgid = self.search_ext(base,scope,filterstr,attrlist,attrsonly,serverctrls,clientctrls,timeout,sizelimit)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:853: in search_ext
return self._ldap_call(
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:340: in _ldap_call
reraise(exc_type, exc_value, exc_traceback)
/usr/local/lib64/python3.8/site-packages/ldap/compat.py:46: in reraise
raise exc_value
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

self = <lib389.DirSrv object at 0x7ff7f27328b0>
func = <built-in method search_ext of LDAP object at 0x7ff7f0d89f90>
args = ('cn=schema', 0, 'objectclass=*', ['nsSchemaCSN'], 0, None, ...)
kwargs = {}, diagnostic_message_success = None, exc_type = None
exc_value = None, exc_traceback = None

def _ldap_call(self,func,*args,**kwargs):
"""
Wrapper method mainly for serializing calls into OpenLDAP libs
and trace logs
"""
self._ldap_object_lock.acquire()
if __debug__:
if self._trace_level>=1:
self._trace_file.write('*** %s %s - %s\n%s\n' % (
repr(self),
self._uri,
'.'.join((self.__class__.__name__,func.__name__)),
pprint.pformat((args,kwargs))
))
if self._trace_level>=9:
traceback.print_stack(limit=self._trace_stack_limit,file=self._trace_file)
diagnostic_message_success = None
try:
try:
> result = func(*args,**kwargs)
E ldap.SERVER_DOWN: {'result': -1, 'desc': "Can't contact LDAP server", 'ctrls': []}

/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:324: SERVER_DOWN
-------------------------------Captured log call--------------------------------
INFO  lib389:ticket47988_test.py:64 ############################################### INFO  lib389:ticket47988_test.py:65 ####### INFO  lib389:ticket47988_test.py:66 ####### test_ticket47988_6 INFO  lib389:ticket47988_test.py:67 ####### INFO  lib389:ticket47988_test.py:68 ###################################################

topology_st = <lib389.topologies.TopologyMain object at 0x7ff7f2b5cc10>

def test_ticket48005_usn(topology_st):
'''
Enable entryusn
Delete all user entries.
Run USN tombstone cleanup task
Shutdown the server
Check if a core file was generated or not
If no core was found, this test case was successful.
'''
log.info("Ticket 48005 usn test...")
topology_st.standalone.plugins.enable(name=PLUGIN_USN)

topology_st.standalone.restart(timeout=10)

try:
> entries = topology_st.standalone.search_s(SUFFIX, ldap.SCOPE_SUBTREE, "(objectclass=inetorgperson)")

/export/tests/tickets/ticket48005_test.py:283:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:870: in search_s
return self.search_ext_s(base,scope,filterstr,attrlist,attrsonly,None,None,timeout=self.timeout)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:864: in search_ext_s
return self.result(msgid,all=1,timeout=timeout)[1]
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:148: in inner
objtype, data = f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:756: in result
resp_type, resp_data, resp_msgid = self.result2(msgid,all,timeout)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:760: in result2
resp_type, resp_data, resp_msgid, resp_ctrls = self.result3(msgid,all,timeout)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:764: in result3
resp_type, resp_data, resp_msgid, decoded_resp_ctrls, retoid, retval = self.result4(
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:774: in result4
ldap_result = self._ldap_call(self._l.result4,msgid,all,timeout,add_ctrls,add_intermediates,add_extop)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:340: in _ldap_call
reraise(exc_type, exc_value, exc_traceback)
/usr/local/lib64/python3.8/site-packages/ldap/compat.py:46: in reraise
raise exc_value
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

self = <lib389.DirSrv object at 0x7ff7f2b5cac0>
func = <built-in method result4 of LDAP object at 0x7ff7f173cb70>
args = (3, 1, -1, 0, 0, 0), kwargs = {}, diagnostic_message_success = None
exc_type = None, exc_value = None, exc_traceback = None

def _ldap_call(self,func,*args,**kwargs):
"""
Wrapper method mainly for serializing calls into OpenLDAP libs
and trace logs
"""
self._ldap_object_lock.acquire()
if __debug__:
if self._trace_level>=1:
self._trace_file.write('*** %s %s - %s\n%s\n' % (
repr(self),
self._uri,
'.'.join((self.__class__.__name__,func.__name__)),
pprint.pformat((args,kwargs))
))
if self._trace_level>=9:
traceback.print_stack(limit=self._trace_stack_limit,file=self._trace_file)
diagnostic_message_success = None
try:
try:
> result = func(*args,**kwargs)
E ldap.NO_SUCH_OBJECT: {'msgtype': 101, 'msgid': 3, 'result': 32, 'desc': 'No such object', 'ctrls': []}

/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:324: NO_SUCH_OBJECT
-------------------------------Captured log call--------------------------------
INFO  tests.tickets.ticket48005_test:ticket48005_test.py:277 Ticket 48005 usn test...

topology_st = <lib389.topologies.TopologyMain object at 0x7ff7f2364430>

def test_ticket48013(topology_st):
'''
Content Synchonization: Test that invalid cookies are caught
'''

cookies = ('#', '##', 'a#a#a', 'a#a#1')

# Enable dynamic plugins
try:
topology_st.standalone.modify_s(DN_CONFIG, [(ldap.MOD_REPLACE, 'nsslapd-dynamic-plugins', b'on')])
except ldap.LDAPError as e:
log.error('Failed to enable dynamic plugin! {}'.format(e.args[0]['desc']))
assert False

# Enable retro changelog
topology_st.standalone.plugins.enable(name=PLUGIN_RETRO_CHANGELOG)

# Enbale content sync plugin
> topology_st.standalone.plugins.enable(name=PLUGIN_REPL_SYNC)

/export/tests/tickets/ticket48013_test.py:61:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
/usr/local/lib/python3.8/site-packages/lib389/plugins.py:2102: in enable
plugin.enable()
/usr/local/lib/python3.8/site-packages/lib389/plugins.py:58: in enable
self.set('nsslapd-pluginEnabled', 'on')
/usr/local/lib/python3.8/site-packages/lib389/_mapped_object.py:446: in set
return self._instance.modify_ext_s(self._dn, [(action, key, value)],
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:613: in modify_ext_s
resp_type, resp_data, resp_msgid, resp_ctrls = self.result3(msgid,all=1,timeout=self.timeout)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:764: in result3
resp_type, resp_data, resp_msgid, decoded_resp_ctrls, retoid, retval = self.result4(
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:774: in result4
ldap_result = self._ldap_call(self._l.result4,msgid,all,timeout,add_ctrls,add_intermediates,add_extop)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:340: in _ldap_call
reraise(exc_type, exc_value, exc_traceback)
/usr/local/lib64/python3.8/site-packages/ldap/compat.py:46: in reraise
raise exc_value
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

self = <lib389.DirSrv object at 0x7ff7f2364310>
func = <built-in method result4 of LDAP object at 0x7ff7f1ffa510>
args = (7, 1, -1, 0, 0, 0), kwargs = {}, diagnostic_message_success = None
exc_type = None, exc_value = None, exc_traceback = None

def _ldap_call(self,func,*args,**kwargs):
"""
Wrapper method mainly for serializing calls into OpenLDAP libs
and trace logs
"""
self._ldap_object_lock.acquire()
if __debug__:
if self._trace_level>=1:
self._trace_file.write('*** %s %s - %s\n%s\n' % (
repr(self),
self._uri,
'.'.join((self.__class__.__name__,func.__name__)),
pprint.pformat((args,kwargs))
))
if self._trace_level>=9:
traceback.print_stack(limit=self._trace_stack_limit,file=self._trace_file)
diagnostic_message_success = None
try:
try:
> result = func(*args,**kwargs)
E ldap.SERVER_DOWN: {'result': -1, 'desc': "Can't contact LDAP server", 'ctrls': []}

/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:324: SERVER_DOWN
-------------------------------Captured log setup-------------------------------
INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 38901, 'ldap-secureport': 63601, 'server-id': 'standalone1', 'suffix': 'dc=example,dc=com'} was created.

topology_st = <lib389.topologies.TopologyMain object at 0x7ff7f2627a00>

def test_run_1(topology_st):
"""
Check nsSSL3Ciphers: +all
All ciphers are enabled except null.
Note: default allowWeakCipher (i.e., off) for +all
"""
_header(topology_st, 'Test Case 2 - Check the ciphers availability for "+all" with default allowWeakCiphers')

topology_st.standalone.simple_bind_s(DN_DM, PASSWORD)
topology_st.standalone.modify_s(CONFIG_DN, [(ldap.MOD_REPLACE, 'nsslapd-errorlog-level', b'64')])
# Make sure allowWeakCipher is not set.
topology_st.standalone.modify_s(ENCRYPTION_DN, [(ldap.MOD_DELETE, 'allowWeakCipher', None)])

log.info("\n######################### Restarting the server ######################\n")
topology_st.standalone.stop(timeout=10)
os.system('mv %s %s.48194_0' % (topology_st.standalone.errlog, topology_st.standalone.errlog))
os.system('touch %s' % (topology_st.standalone.errlog))
time.sleep(2)
topology_st.standalone.start(timeout=120)

> connectWithOpenssl(topology_st, 'DES-CBC3-SHA', False)

/export/tests/tickets/ticket48194_test.py:158:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

topology_st = <lib389.topologies.TopologyMain object at 0x7ff7f2627a00>
cipher = 'DES-CBC3-SHA', expect = False

def connectWithOpenssl(topology_st, cipher, expect):
"""
Connect with the given cipher
Condition:
If expect is True, the handshake should be successful.
If expect is False, the handshake should be refused with
access log: "Cannot communicate securely with peer:
no common encryption algorithm(s)."
"""
log.info("Testing %s -- expect to handshake %s", cipher, "successfully" if expect else "failed")

myurl = 'localhost:%s' % LDAPSPORT
cmdline = ['/usr/bin/openssl', 's_client', '-connect', myurl, '-cipher', cipher]

strcmdline = " ".join(cmdline)
log.info("Running cmdline: %s", strcmdline)

try:
proc = subprocess.Popen(cmdline, stdout=subprocess.PIPE, stdin=subprocess.PIPE, stderr=subprocess.STDOUT)
except ValueError:
log.info("%s failed: %s", cmdline, ValueError)
proc.kill()

while True:
l = proc.stdout.readline()
if l == b"":
break
if b'Cipher is' in l:
log.info("Found: %s", l)
if expect:
if b'(NONE)' in l:
assert False
else:
proc.stdin.close()
assert True
else:
if b'(NONE)' in l:
assert True
else:
proc.stdin.close()
> assert False
E assert False

/export/tests/tickets/ticket48194_test.py:117: AssertionError
-------------------------------Captured log call--------------------------------
INFO  lib389:ticket48194_test.py:40 ############################################### INFO  lib389:ticket48194_test.py:41 ####### Test Case 2 - Check the ciphers availability for "+all" with default allowWeakCiphers INFO  lib389:ticket48194_test.py:42 ############################################### INFO  lib389.utils:ticket48194_test.py:151 ######################### Restarting the server ###################### INFO  lib389.utils:ticket48194_test.py:86 Testing DES-CBC3-SHA -- expect to handshake failed INFO  lib389.utils:ticket48194_test.py:92 Running cmdline: /usr/bin/openssl s_client -connect localhost:63601 -cipher DES-CBC3-SHA INFO  lib389.utils:ticket48194_test.py:105 Found: b'New, TLSv1.3, Cipher is TLS_AES_128_GCM_SHA256\n'

topology_st = <lib389.topologies.TopologyMain object at 0x7ff7f2627a00>

def test_run_2(topology_st):
"""
Check nsSSL3Ciphers: +rsa_aes_128_sha,+rsa_aes_256_sha
rsa_aes_128_sha, tls_rsa_aes_128_sha, rsa_aes_256_sha, tls_rsa_aes_256_sha are enabled.
default allowWeakCipher
"""
_header(topology_st,
'Test Case 3 - Check the ciphers availability for "+rsa_aes_128_sha,+rsa_aes_256_sha" with default allowWeakCipher')

topology_st.standalone.simple_bind_s(DN_DM, PASSWORD)
topology_st.standalone.modify_s(ENCRYPTION_DN,
[(ldap.MOD_REPLACE, 'nsSSL3Ciphers', b'+rsa_aes_128_sha,+rsa_aes_256_sha')])

log.info("\n######################### Restarting the server ######################\n")
topology_st.standalone.stop(timeout=10)
os.system('mv %s %s.48194_1' % (topology_st.standalone.errlog, topology_st.standalone.errlog))
os.system('touch %s' % (topology_st.standalone.errlog))
time.sleep(2)
topology_st.standalone.start(timeout=120)

connectWithOpenssl(topology_st, 'DES-CBC3-SHA', False)
connectWithOpenssl(topology_st, 'AES256-SHA256', False)
> connectWithOpenssl(topology_st, 'AES128-SHA', True)

/export/tests/tickets/ticket48194_test.py:184:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

topology_st = <lib389.topologies.TopologyMain object at 0x7ff7f2627a00>
cipher = 'AES128-SHA', expect = True

def connectWithOpenssl(topology_st, cipher, expect):
"""
Connect with the given cipher
Condition:
If expect is True, the handshake should be successful.
If expect is False, the handshake should be refused with
access log: "Cannot communicate securely with peer:
no common encryption algorithm(s)."
"""
log.info("Testing %s -- expect to handshake %s", cipher, "successfully" if expect else "failed")

myurl = 'localhost:%s' % LDAPSPORT
cmdline = ['/usr/bin/openssl', 's_client', '-connect', myurl, '-cipher', cipher]

strcmdline = " ".join(cmdline)
log.info("Running cmdline: %s", strcmdline)

try:
proc = subprocess.Popen(cmdline, stdout=subprocess.PIPE, stdin=subprocess.PIPE, stderr=subprocess.STDOUT)
except ValueError:
log.info("%s failed: %s", cmdline, ValueError)
proc.kill()

while True:
l = proc.stdout.readline()
if l == b"":
break
if b'Cipher is' in l:
log.info("Found: %s", l)
if expect:
if b'(NONE)' in l:
> assert False
E assert False

/export/tests/tickets/ticket48194_test.py:108: AssertionError
-------------------------------Captured log call--------------------------------
INFO  lib389:ticket48194_test.py:40 ############################################### INFO  lib389:ticket48194_test.py:41 ####### Test Case 3 - Check the ciphers availability for "+rsa_aes_128_sha,+rsa_aes_256_sha" with default allowWeakCipher INFO  lib389:ticket48194_test.py:42 ############################################### INFO  lib389.utils:ticket48194_test.py:175 ######################### Restarting the server ###################### INFO  lib389.utils:ticket48194_test.py:86 Testing DES-CBC3-SHA -- expect to handshake failed INFO  lib389.utils:ticket48194_test.py:92 Running cmdline: /usr/bin/openssl s_client -connect localhost:63601 -cipher DES-CBC3-SHA INFO  lib389.utils:ticket48194_test.py:105 Found: b'New, (NONE), Cipher is (NONE)\n' INFO  lib389.utils:ticket48194_test.py:86 Testing AES256-SHA256 -- expect to handshake failed INFO  lib389.utils:ticket48194_test.py:92 Running cmdline: /usr/bin/openssl s_client -connect localhost:63601 -cipher AES256-SHA256 INFO  lib389.utils:ticket48194_test.py:105 Found: b'New, (NONE), Cipher is (NONE)\n' INFO  lib389.utils:ticket48194_test.py:86 Testing AES128-SHA -- expect to handshake successfully INFO  lib389.utils:ticket48194_test.py:92 Running cmdline: /usr/bin/openssl s_client -connect localhost:63601 -cipher AES128-SHA INFO  lib389.utils:ticket48194_test.py:105 Found: b'New, (NONE), Cipher is (NONE)\n'

topology_st = <lib389.topologies.TopologyMain object at 0x7ff7f2627a00>

def test_run_4(topology_st):
"""
Check no nsSSL3Ciphers
Default ciphers are enabled.
default allowWeakCipher
"""
_header(topology_st, 'Test Case 5 - Check no nsSSL3Ciphers (-all) with default allowWeakCipher')

topology_st.standalone.simple_bind_s(DN_DM, PASSWORD)
topology_st.standalone.modify_s(ENCRYPTION_DN, [(ldap.MOD_DELETE, 'nsSSL3Ciphers', b'-all')])

log.info("\n######################### Restarting the server ######################\n")
topology_st.standalone.stop(timeout=10)
os.system('mv %s %s.48194_3' % (topology_st.standalone.errlog, topology_st.standalone.errlog))
os.system('touch %s' % (topology_st.standalone.errlog))
time.sleep(2)
topology_st.standalone.start(timeout=120)

> connectWithOpenssl(topology_st, 'DES-CBC3-SHA', False)

/export/tests/tickets/ticket48194_test.py:228:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

topology_st = <lib389.topologies.TopologyMain object at 0x7ff7f2627a00>
cipher = 'DES-CBC3-SHA', expect = False

def connectWithOpenssl(topology_st, cipher, expect):
"""
Connect with the given cipher
Condition:
If expect is True, the handshake should be successful.
If expect is False, the handshake should be refused with
access log: "Cannot communicate securely with peer:
no common encryption algorithm(s)."
"""
log.info("Testing %s -- expect to handshake %s", cipher, "successfully" if expect else "failed")

myurl = 'localhost:%s' % LDAPSPORT
cmdline = ['/usr/bin/openssl', 's_client', '-connect', myurl, '-cipher', cipher]

strcmdline = " ".join(cmdline)
log.info("Running cmdline: %s", strcmdline)

try:
proc = subprocess.Popen(cmdline, stdout=subprocess.PIPE, stdin=subprocess.PIPE, stderr=subprocess.STDOUT)
except ValueError:
log.info("%s failed: %s", cmdline, ValueError)
proc.kill()

while True:
l = proc.stdout.readline()
if l == b"":
break
if b'Cipher is' in l:
log.info("Found: %s", l)
if expect:
if b'(NONE)' in l:
assert False
else:
proc.stdin.close()
assert True
else:
if b'(NONE)' in l:
assert True
else:
proc.stdin.close()
> assert False
E assert False

/export/tests/tickets/ticket48194_test.py:117: AssertionError
-------------------------------Captured log call--------------------------------
INFO  lib389:ticket48194_test.py:40 ############################################### INFO  lib389:ticket48194_test.py:41 ####### Test Case 5 - Check no nsSSL3Ciphers (-all) with default allowWeakCipher INFO  lib389:ticket48194_test.py:42 ############################################### INFO  lib389.utils:ticket48194_test.py:221 ######################### Restarting the server ###################### INFO  lib389.utils:ticket48194_test.py:86 Testing DES-CBC3-SHA -- expect to handshake failed INFO  lib389.utils:ticket48194_test.py:92 Running cmdline: /usr/bin/openssl s_client -connect localhost:63601 -cipher DES-CBC3-SHA INFO  lib389.utils:ticket48194_test.py:105 Found: b'New, TLSv1.3, Cipher is TLS_AES_128_GCM_SHA256\n'

topology_st = <lib389.topologies.TopologyMain object at 0x7ff7f2627a00>

def test_run_5(topology_st):
"""
Check nsSSL3Ciphers: default
Default ciphers are enabled.
default allowWeakCipher
"""
_header(topology_st, 'Test Case 6 - Check default nsSSL3Ciphers (default setting) with default allowWeakCipher')

topology_st.standalone.simple_bind_s(DN_DM, PASSWORD)
topology_st.standalone.modify_s(ENCRYPTION_DN, [(ldap.MOD_REPLACE, 'nsSSL3Ciphers', b'default')])

log.info("\n######################### Restarting the server ######################\n")
topology_st.standalone.stop(timeout=10)
os.system('mv %s %s.48194_4' % (topology_st.standalone.errlog, topology_st.standalone.errlog))
os.system('touch %s' % (topology_st.standalone.errlog))
time.sleep(2)
topology_st.standalone.start(timeout=120)

> connectWithOpenssl(topology_st, 'DES-CBC3-SHA', False)

/export/tests/tickets/ticket48194_test.py:250:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

topology_st = <lib389.topologies.TopologyMain object at 0x7ff7f2627a00>
cipher = 'DES-CBC3-SHA', expect = False

def connectWithOpenssl(topology_st, cipher, expect):
"""
Connect with the given cipher
Condition:
If expect is True, the handshake should be successful.
If expect is False, the handshake should be refused with
access log: "Cannot communicate securely with peer:
no common encryption algorithm(s)."
"""
log.info("Testing %s -- expect to handshake %s", cipher, "successfully" if expect else "failed")

myurl = 'localhost:%s' % LDAPSPORT
cmdline = ['/usr/bin/openssl', 's_client', '-connect', myurl, '-cipher', cipher]

strcmdline = " ".join(cmdline)
log.info("Running cmdline: %s", strcmdline)

try:
proc = subprocess.Popen(cmdline, stdout=subprocess.PIPE, stdin=subprocess.PIPE, stderr=subprocess.STDOUT)
except ValueError:
log.info("%s failed: %s", cmdline, ValueError)
proc.kill()

while True:
l = proc.stdout.readline()
if l == b"":
break
if b'Cipher is' in l:
log.info("Found: %s", l)
if expect:
if b'(NONE)' in l:
assert False
else:
proc.stdin.close()
assert True
else:
if b'(NONE)' in l:
assert True
else:
proc.stdin.close()
> assert False
E assert False

/export/tests/tickets/ticket48194_test.py:117: AssertionError
-------------------------------Captured log call--------------------------------
INFO  lib389:ticket48194_test.py:40 ############################################### INFO  lib389:ticket48194_test.py:41 ####### Test Case 6 - Check default nsSSL3Ciphers (default setting) with default allowWeakCipher INFO  lib389:ticket48194_test.py:42 ############################################### INFO  lib389.utils:ticket48194_test.py:243 ######################### Restarting the server ###################### INFO  lib389.utils:ticket48194_test.py:86 Testing DES-CBC3-SHA -- expect to handshake failed INFO  lib389.utils:ticket48194_test.py:92 Running cmdline: /usr/bin/openssl s_client -connect localhost:63601 -cipher DES-CBC3-SHA INFO  lib389.utils:ticket48194_test.py:105 Found: b'New, TLSv1.3, Cipher is TLS_AES_128_GCM_SHA256\n'

topology_st = <lib389.topologies.TopologyMain object at 0x7ff7f2627a00>

def test_run_6(topology_st):
"""
Check nsSSL3Ciphers: +all,-TLS_RSA_WITH_AES_256_CBC_SHA256
All ciphers are disabled.
default allowWeakCipher
"""
_header(topology_st,
'Test Case 7 - Check nsSSL3Ciphers: +all,-TLS_RSA_WITH_AES_256_CBC_SHA256 with default allowWeakCipher')

topology_st.standalone.simple_bind_s(DN_DM, PASSWORD)
topology_st.standalone.modify_s(ENCRYPTION_DN,
[(ldap.MOD_REPLACE, 'nsSSL3Ciphers', b'+all,-TLS_RSA_WITH_AES_256_CBC_SHA256')])

log.info("\n######################### Restarting the server ######################\n")
topology_st.standalone.stop(timeout=10)
os.system('mv %s %s.48194_5' % (topology_st.standalone.errlog, topology_st.standalone.errlog))
os.system('touch %s' % (topology_st.standalone.errlog))
time.sleep(2)
topology_st.standalone.start(timeout=120)

> connectWithOpenssl(topology_st, 'DES-CBC3-SHA', False)

/export/tests/tickets/ticket48194_test.py:274:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

topology_st = <lib389.topologies.TopologyMain object at 0x7ff7f2627a00>
cipher = 'DES-CBC3-SHA', expect = False

def connectWithOpenssl(topology_st, cipher, expect):
"""
Connect with the given cipher
Condition:
If expect is True, the handshake should be successful.
If expect is False, the handshake should be refused with
access log: "Cannot communicate securely with peer:
no common encryption algorithm(s)."
"""
log.info("Testing %s -- expect to handshake %s", cipher, "successfully" if expect else "failed")

myurl = 'localhost:%s' % LDAPSPORT
cmdline = ['/usr/bin/openssl', 's_client', '-connect', myurl, '-cipher', cipher]

strcmdline = " ".join(cmdline)
log.info("Running cmdline: %s", strcmdline)

try:
proc = subprocess.Popen(cmdline, stdout=subprocess.PIPE, stdin=subprocess.PIPE, stderr=subprocess.STDOUT)
except ValueError:
log.info("%s failed: %s", cmdline, ValueError)
proc.kill()

while True:
l = proc.stdout.readline()
if l == b"":
break
if b'Cipher is' in l:
log.info("Found: %s", l)
if expect:
if b'(NONE)' in l:
assert False
else:
proc.stdin.close()
assert True
else:
if b'(NONE)' in l:
assert True
else:
proc.stdin.close()
> assert False
E assert False

/export/tests/tickets/ticket48194_test.py:117: AssertionError
-------------------------------Captured log call--------------------------------
INFO  lib389:ticket48194_test.py:40 ############################################### INFO  lib389:ticket48194_test.py:41 ####### Test Case 7 - Check nsSSL3Ciphers: +all,-TLS_RSA_WITH_AES_256_CBC_SHA256 with default allowWeakCipher INFO  lib389:ticket48194_test.py:42 ############################################### INFO  lib389.utils:ticket48194_test.py:267 ######################### Restarting the server ###################### INFO  lib389.utils:ticket48194_test.py:86 Testing DES-CBC3-SHA -- expect to handshake failed INFO  lib389.utils:ticket48194_test.py:92 Running cmdline: /usr/bin/openssl s_client -connect localhost:63601 -cipher DES-CBC3-SHA INFO  lib389.utils:ticket48194_test.py:105 Found: b'New, TLSv1.3, Cipher is TLS_AES_128_GCM_SHA256\n'

topology_st = <lib389.topologies.TopologyMain object at 0x7ff7f2627a00>

def test_run_8(topology_st):
"""
Check nsSSL3Ciphers: default + allowWeakCipher: off
Strong Default ciphers are enabled.
"""
_header(topology_st, 'Test Case 9 - Check default nsSSL3Ciphers (default setting + allowWeakCipher: off)')

topology_st.standalone.simple_bind_s(DN_DM, PASSWORD)
topology_st.standalone.modify_s(ENCRYPTION_DN, [(ldap.MOD_REPLACE, 'nsSSL3Ciphers', b'default'),
(ldap.MOD_REPLACE, 'allowWeakCipher', b'off')])

log.info("\n######################### Restarting the server ######################\n")
topology_st.standalone.stop(timeout=10)
os.system('mv %s %s.48194_7' % (topology_st.standalone.errlog, topology_st.standalone.errlog))
os.system('touch %s' % (topology_st.standalone.errlog))
time.sleep(2)
topology_st.standalone.start(timeout=120)

> connectWithOpenssl(topology_st, 'DES-CBC3-SHA', False)

/export/tests/tickets/ticket48194_test.py:297:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

topology_st = <lib389.topologies.TopologyMain object at 0x7ff7f2627a00>
cipher = 'DES-CBC3-SHA', expect = False

def connectWithOpenssl(topology_st, cipher, expect):
"""
Connect with the given cipher
Condition:
If expect is True, the handshake should be successful.
If expect is False, the handshake should be refused with
access log: "Cannot communicate securely with peer:
no common encryption algorithm(s)."
"""
log.info("Testing %s -- expect to handshake %s", cipher, "successfully" if expect else "failed")

myurl = 'localhost:%s' % LDAPSPORT
cmdline = ['/usr/bin/openssl', 's_client', '-connect', myurl, '-cipher', cipher]

strcmdline = " ".join(cmdline)
log.info("Running cmdline: %s", strcmdline)

try:
proc = subprocess.Popen(cmdline, stdout=subprocess.PIPE, stdin=subprocess.PIPE, stderr=subprocess.STDOUT)
except ValueError:
log.info("%s failed: %s", cmdline, ValueError)
proc.kill()

while True:
l = proc.stdout.readline()
if l == b"":
break
if b'Cipher is' in l:
log.info("Found: %s", l)
if expect:
if b'(NONE)' in l:
assert False
else:
proc.stdin.close()
assert True
else:
if b'(NONE)' in l:
assert True
else:
proc.stdin.close()
> assert False
E assert False

/export/tests/tickets/ticket48194_test.py:117: AssertionError
-------------------------------Captured log call--------------------------------
INFO  lib389:ticket48194_test.py:40 ############################################### INFO  lib389:ticket48194_test.py:41 ####### Test Case 9 - Check default nsSSL3Ciphers (default setting + allowWeakCipher: off) INFO  lib389:ticket48194_test.py:42 ############################################### INFO  lib389.utils:ticket48194_test.py:290 ######################### Restarting the server ###################### INFO  lib389.utils:ticket48194_test.py:86 Testing DES-CBC3-SHA -- expect to handshake failed INFO  lib389.utils:ticket48194_test.py:92 Running cmdline: /usr/bin/openssl s_client -connect localhost:63601 -cipher DES-CBC3-SHA INFO  lib389.utils:ticket48194_test.py:105 Found: b'New, TLSv1.3, Cipher is TLS_AES_128_GCM_SHA256\n'

topology_m2 = <lib389.topologies.TopologyMain object at 0x7ff7f1113550>
entries = None

def test_ticket48266_count_csn_evaluation(topology_m2, entries):
ents = topology_m2.ms["master1"].agreement.list(suffix=SUFFIX)
assert len(ents) == 1
> first_csn = _get_first_not_replicated_csn(topology_m2)

/export/tests/tickets/ticket48266_test.py:176:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

topology_m2 = <lib389.topologies.TopologyMain object at 0x7ff7f1113550>

def _get_first_not_replicated_csn(topology_m2):
name = "cn=%s2,%s" % (NEW_ACCOUNT, SUFFIX)

# read the first CSN that will not be replicated
mod = [(ldap.MOD_REPLACE, 'telephonenumber', ensure_bytes('123456'))]
topology_m2.ms["master1"].modify_s(name, mod)
msgid = topology_m2.ms["master1"].search_ext(name, ldap.SCOPE_SUBTREE, 'objectclass=*', ['nscpentrywsi'])
rtype, rdata, rmsgid = topology_m2.ms["master1"].result2(msgid)
attrs = None
for dn, raw_attrs in rdata:
topology_m2.ms["master1"].log.info("dn: %s" % dn)
if 'nscpentrywsi' in raw_attrs:
attrs = raw_attrs['nscpentrywsi']
assert attrs
for attr in attrs:
if ensure_str(attr.lower()).startswith('telephonenumber'):
break
assert attr

log.info("############# %s " % name)
# now retrieve the CSN of the operation we are looking for
csn = None
found_ops = topology_m2.ms['master1'].ds_access_log.match(".*MOD dn=\"%s\".*" % name)
assert(len(found_ops) > 0)
found_op = topology_m2.ms['master1'].ds_access_log.parse_line(found_ops[-1])
log.info(found_op)

# Now look for the related CSN
found_csns = topology_m2.ms['master1'].ds_access_log.match(".*conn=%s op=%s RESULT.*" % (found_op['conn'], found_op['op']))
assert(len(found_csns) > 0)
found_csn = topology_m2.ms['master1'].ds_access_log.parse_line(found_csns[-1])
log.info(found_csn)
> return found_csn['csn']
E KeyError: 'csn'

/export/tests/tickets/ticket48266_test.py:147: KeyError
-------------------------------Captured log call--------------------------------
INFO  lib389:ticket48266_test.py:125 dn: cn=new_account2,dc=example,dc=com INFO  tests.tickets.ticket48266_test:ticket48266_test.py:134 ############# cn=new_account2,dc=example,dc=com INFO  tests.tickets.ticket48266_test:ticket48266_test.py:140 {'action': 'MOD', 'timestamp': '[07/Oct/2020:22:50:08.717223405 -0400]', 'conn': '1', 'op': '12', 'rem': 'dn="cn=new_account2,dc=example,dc=com"', 'datetime': datetime.datetime(2020, 9, 7, 22, 0, 0, 717223, tzinfo=tzoffset(None, -14400))} INFO  tests.tickets.ticket48266_test:ticket48266_test.py:146 {'action': 'RESULT', 'timestamp': '[07/Oct/2020:22:50:08.796137425 -0400]', 'conn': '1', 'op': '12', 'rem': 'err=0 tag=103 nentries=0 wtime=0.000252898 optime=0.078940277 etime=0.079183434 csn=5f7e7e60000000010000', 'datetime': datetime.datetime(2020, 9, 7, 22, 0, 0, 796137, tzinfo=tzoffset(None, -14400))}

topology_m1h1c1 = <lib389.topologies.TopologyMain object at 0x7ff7f1cf0910>

def test_ticket48325(topology_m1h1c1):
"""
Test that the RUV element order is correctly maintained when promoting
a hub or consumer.
"""

#
# Promote consumer to master
#
C1 = topology_m1h1c1.cs["consumer1"]
M1 = topology_m1h1c1.ms["master1"]
H1 = topology_m1h1c1.hs["hub1"]
repl = ReplicationManager(DEFAULT_SUFFIX)
> repl._ensure_changelog(C1)

/export/tests/tickets/ticket48325_test.py:53:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
/usr/local/lib/python3.8/site-packages/lib389/replica.py:1912: in _ensure_changelog
cl.create(properties={
/usr/local/lib/python3.8/site-packages/lib389/_mapped_object.py:971: in create
return self._create(rdn, properties, basedn, ensure=False)
/usr/local/lib/python3.8/site-packages/lib389/_mapped_object.py:946: in _create
self._instance.add_ext_s(e, serverctrls=self._server_controls, clientctrls=self._client_controls, escapehatch='i am sure')
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:176: in inner
return f(ent.dn, ent.toTupleList(), *args[2:])
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:425: in add_ext_s
resp_type, resp_data, resp_msgid, resp_ctrls = self.result3(msgid,all=1,timeout=self.timeout)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:764: in result3
resp_type, resp_data, resp_msgid, decoded_resp_ctrls, retoid, retval = self.result4(
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:774: in result4
ldap_result = self._ldap_call(self._l.result4,msgid,all,timeout,add_ctrls,add_intermediates,add_extop)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:340: in _ldap_call
reraise(exc_type, exc_value, exc_traceback)
/usr/local/lib64/python3.8/site-packages/ldap/compat.py:46: in reraise
raise exc_value
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

self = <lib389.DirSrv object at 0x7ff7f1cdf1f0>
func = <built-in method result4 of LDAP object at 0x7ff7f1cf07b0>
args = (15, 1, -1, 0, 0, 0), kwargs = {}, diagnostic_message_success = None
exc_type = None, exc_value = None, exc_traceback = None

def _ldap_call(self,func,*args,**kwargs):
"""
Wrapper method mainly for serializing calls into OpenLDAP libs
and trace logs
"""
self._ldap_object_lock.acquire()
if __debug__:
if self._trace_level>=1:
self._trace_file.write('*** %s %s - %s\n%s\n' % (
repr(self),
self._uri,
'.'.join((self.__class__.__name__,func.__name__)),
pprint.pformat((args,kwargs))
))
if self._trace_level>=9:
traceback.print_stack(limit=self._trace_stack_limit,file=self._trace_file)
diagnostic_message_success = None
try:
try:
> result = func(*args,**kwargs)
E ldap.UNWILLING_TO_PERFORM: {'msgtype': 105, 'msgid': 15, 'result': 53, 'desc': 'Server is unwilling to perform', 'ctrls': [], 'info': 'Changelog configuration is part of the backend configuration'}

/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:324: UNWILLING_TO_PERFORM
-------------------------------Captured log setup-------------------------------
INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 39001, 'ldap-secureport': 63701, 'server-id': 'master1', 'suffix': 'dc=example,dc=com'} was created. INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 39101, 'ldap-secureport': 63801, 'server-id': 'hub1', 'suffix': 'dc=example,dc=com'} was created. INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 39201, 'ldap-secureport': 63901, 'server-id': 'consumer1', 'suffix': 'dc=example,dc=com'} was created. INFO  lib389.topologies:topologies.py:515 Creating replication topology. INFO  lib389.replica:replica.py:2068 SUCCESS: bootstrap to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39101 completed INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39101 is was created INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39101 is NOT working (expect f0ff2ae9-82a9-4aca-9660-844e1ba442ce / got description=None) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39101 is working INFO  lib389.replica:replica.py:2195 SUCCESS: joined consumer from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39101 INFO  lib389.replica:replica.py:2068 SUCCESS: bootstrap to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39201 completed INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39101 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39201 is was created INFO  lib389.replica:replica.py:2252 SUCCESS: joined consumer from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39101 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39201 INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39201 is NOT working (expect c92b9687-08c0-4544-8b22-b0518efb0e6e / got description=f0ff2ae9-82a9-4aca-9660-844e1ba442ce) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39201 is working

topology_st = <lib389.topologies.TopologyMain object at 0x7ff7f14aa310>

def test_ticket48637(topology_st):
"""Test for entry cache corruption

This requires automember and managed entry plugins to be configured.

Then remove the group that automember would use to trigger a failure when
adding a new entry. Automember fails, and then managed entry also fails.

Make sure a base search on the entry returns error 32
"""

if DEBUGGING:
# Add debugging steps(if any)...
pass

#
# Add our setup entries
#
try:
topology_st.standalone.add_s(Entry((PEOPLE_OU, {
'objectclass': 'top organizationalunit'.split(),
'ou': 'people'})))
except ldap.ALREADY_EXISTS:
pass
except ldap.LDAPError as e:
log.fatal('Failed to add people ou: ' + str(e))
assert False

try:
topology_st.standalone.add_s(Entry((GROUP_OU, {
'objectclass': 'top organizationalunit'.split(),
'ou': 'groups'})))
except ldap.ALREADY_EXISTS:
pass
except ldap.LDAPError as e:
log.fatal('Failed to add groups ou: ' + str(e))
assert False

try:
topology_st.standalone.add_s(Entry((MEP_OU, {
'objectclass': 'top extensibleObject'.split(),
'ou': 'mep'})))
except ldap.LDAPError as e:
log.fatal('Failed to add MEP ou: ' + str(e))
assert False

try:
topology_st.standalone.add_s(Entry((MEP_TEMPLATE, {
'objectclass': 'top mepTemplateEntry'.split(),
'cn': 'mep template',
'mepRDNAttr': 'cn',
'mepStaticAttr': 'objectclass: groupofuniquenames',
'mepMappedAttr': 'cn: $uid'})))
except ldap.LDAPError as e:
log.fatal('Failed to add MEP ou: ' + str(e))
assert False

#
# Configure automember
#
try:
topology_st.standalone.add_s(Entry((AUTO_DN, {
'cn': 'All Users',
'objectclass': ['top', 'autoMemberDefinition'],
'autoMemberScope': 'dc=example,dc=com',
'autoMemberFilter': 'objectclass=person',
'autoMemberDefaultGroup': GROUP_DN,
'autoMemberGroupingAttr': 'uniquemember:dn'})))
except ldap.LDAPError as e:
log.fatal('Failed to configure automember plugin : ' + str(e))
assert False

#
# Configure managed entry plugin
#
try:
topology_st.standalone.add_s(Entry((MEP_DN, {
'cn': 'MEP Definition',
'objectclass': ['top', 'extensibleObject'],
'originScope': 'ou=people,dc=example,dc=com',
'originFilter': 'objectclass=person',
'managedBase': 'ou=groups,dc=example,dc=com',
'managedTemplate': MEP_TEMPLATE})))
except ldap.LDAPError as e:
log.fatal('Failed to configure managed entry plugin : ' + str(e))
assert False

#
# Restart DS
#
topology_st.standalone.restart(timeout=30)

#
# Add entry that should fail since the automember group does not exist
#
try:
topology_st.standalone.add_s(Entry((USER_DN, {
'uid': 'test',
'objectclass': ['top', 'person', 'extensibleObject'],
'sn': 'test',
'cn': 'test'})))
except ldap.LDAPError as e:
pass

#
# Search for the entry - it should not be returned
#
try:
entry = topology_st.standalone.search_s(USER_DN, ldap.SCOPE_SUBTREE,
'objectclass=*')
if entry:
log.fatal('Entry was incorrectly returned')
> assert False
E assert False

/export/tests/tickets/ticket48637_test.py:139: AssertionError
-------------------------------Captured log setup-------------------------------
INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 38901, 'ldap-secureport': 63601, 'server-id': 'standalone1', 'suffix': 'dc=example,dc=com'} was created.
-------------------------------Captured log call--------------------------------
CRITICAL tests.tickets.ticket48637_test:ticket48637_test.py:138 Entry was incorrectly returned

Fixture "add_entry" called directly. Fixtures are not meant to be called directly,
but are created automatically when test functions request them as parameters.
See https://docs.pytest.org/en/latest/fixture.html for more information about fixtures, and
https://docs.pytest.org/en/latest/deprecations.html#calling-fixtures-directly about how to update your code.
-------------------------------Captured log setup-------------------------------
INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 39001, 'ldap-secureport': 63701, 'server-id': 'master1', 'suffix': 'dc=example,dc=com'} was created. INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 39002, 'ldap-secureport': 63702, 'server-id': 'master2', 'suffix': 'dc=example,dc=com'} was created. INFO  lib389.topologies:topologies.py:148 Creating replication topology. INFO  lib389.topologies:topologies.py:162 Joining master master2 to master1 ... INFO  lib389.replica:replica.py:2068 SUCCESS: bootstrap to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 completed INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is was created INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is was created INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is NOT working (expect 6423b07c-6e30-4f60-8582-aa95801deb41 / got description=None) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 5331b131-ee90-4084-9a1b-49cea5547e0d / got description=6423b07c-6e30-4f60-8582-aa95801deb41) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is working INFO  lib389.replica:replica.py:2137 SUCCESS: joined master from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 INFO  lib389.topologies:topologies.py:170 Ensuring master master1 to master2 ... INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 already exists INFO  lib389.topologies:topologies.py:170 Ensuring master master2 to master1 ... INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 already exists
-------------------------------Captured log call--------------------------------
INFO  tests.tickets.ticket48784_test:ticket48784_test.py:90 Ticket 48784 - Allow usage of OpenLDAP libraries that don't use NSS for crypto INFO  tests.tickets.ticket48784_test:ticket48784_test.py:50 ######################### Configure SSL/TLS agreements ###################### INFO  tests.tickets.ticket48784_test:ticket48784_test.py:51 ######################## master1 <-- startTLS -> master2 ##################### INFO  tests.tickets.ticket48784_test:ticket48784_test.py:53 ##### Update the agreement of master1 INFO  tests.tickets.ticket48784_test:ticket48784_test.py:58 ##### Update the agreement of master2 INFO  tests.tickets.ticket48784_test:ticket48784_test.py:68 ######################### Configure SSL/TLS agreements Done ######################

topology_st = <lib389.topologies.TopologyMain object at 0x7ff7f260ab50>

def test_ticket48798(topology_st):
"""
Test DH param sizes offered by DS.

"""
topology_st.standalone.enable_tls()

# Confirm that we have a connection, and that it has DH

# Open a socket to the port.
# Check the security settings.
> size = check_socket_dh_param_size(topology_st.standalone.host, topology_st.standalone.sslport)

/export/tests/tickets/ticket48798_test.py:46:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
/export/tests/tickets/ticket48798_test.py:23: in check_socket_dh_param_size
output = check_output(cmd, shell=True)
/usr/lib64/python3.8/subprocess.py:411: in check_output
return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

input = None, capture_output = False, timeout = None, check = True
popenargs = ('echo quit | openssl s_client -connect ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:63601 -msg -cipher DH | grep -A 1 ServerKeyExchange',)
kwargs = {'shell': True, 'stdout': -1}
process = <subprocess.Popen object at 0x7ff7f1804190>, stdout = b''
stderr = None, retcode = 1

def run(*popenargs,
input=None, capture_output=False, timeout=None, check=False, **kwargs):
"""Run command with arguments and return a CompletedProcess instance.

The returned instance will have attributes args, returncode, stdout and
stderr. By default, stdout and stderr are not captured, and those attributes
will be None. Pass stdout=PIPE and/or stderr=PIPE in order to capture them.

If check is True and the exit code was non-zero, it raises a
CalledProcessError. The CalledProcessError object will have the return code
in the returncode attribute, and output & stderr attributes if those streams
were captured.

If timeout is given, and the process takes too long, a TimeoutExpired
exception will be raised.

There is an optional argument "input", allowing you to
pass bytes or a string to the subprocess's stdin. If you use this argument
you may not also use the Popen constructor's "stdin" argument, as
it will be used internally.

By default, all communication is in bytes, and therefore any "input" should
be bytes, and the stdout and stderr will be bytes. If in text mode, any
"input" should be a string, and stdout and stderr will be strings decoded
according to locale encoding, or by "encoding" if set. Text mode is
triggered by setting any of text, encoding, errors or universal_newlines.

The other arguments are the same as for the Popen constructor.
"""
if input is not None:
if kwargs.get('stdin') is not None:
raise ValueError('stdin and input arguments may not both be used.')
kwargs['stdin'] = PIPE

if capture_output:
if kwargs.get('stdout') is not None or kwargs.get('stderr') is not None:
raise ValueError('stdout and stderr arguments may not be used '
'with capture_output.')
kwargs['stdout'] = PIPE
kwargs['stderr'] = PIPE

with Popen(*popenargs, **kwargs) as process:
try:
stdout, stderr = process.communicate(input, timeout=timeout)
except TimeoutExpired as exc:
process.kill()
if _mswindows:
# Windows accumulates the output in a single blocking
# read() call run on child threads, with the timeout
# being done in a join() on those threads. communicate()
# _after_ kill() is required to collect that and add it
# to the exception.
exc.stdout, exc.stderr = process.communicate()
else:
# POSIX _communicate already populated the output so
# far into the TimeoutExpired exception.
process.wait()
raise
except: # Including KeyboardInterrupt, communicate handled that.
process.kill()
# We don't call process.wait() as .__exit__ does that for us.
raise
retcode = process.poll()
if check and retcode:
> raise CalledProcessError(retcode, process.args,
output=stdout, stderr=stderr)
E subprocess.CalledProcessError: Command 'echo quit | openssl s_client -connect ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:63601 -msg -cipher DH | grep -A 1 ServerKeyExchange' returned non-zero exit status 1.

/usr/lib64/python3.8/subprocess.py:512: CalledProcessError
-------------------------------Captured log setup-------------------------------
INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 38901, 'ldap-secureport': 63601, 'server-id': 'standalone1', 'suffix': 'dc=example,dc=com'} was created.
------------------------------Captured stderr call------------------------------
depth=1 C = AU, ST = Queensland, L = 389ds, O = testing, CN = ssca.389ds.example.com verify return:1 depth=0 C = AU, ST = Queensland, L = 389ds, O = testing, GN = 6f6b771f-b95d-4f92-8b6f-eba623e088bc, CN = ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com verify error:num=7:certificate signature failure verify return:1 depth=0 C = AU, ST = Queensland, L = 389ds, O = testing, GN = 6f6b771f-b95d-4f92-8b6f-eba623e088bc, CN = ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com verify return:1 DONE

topology_st = <lib389.topologies.TopologyMain object at 0x7ff7f16ae2b0>

def test_ticket48961_storagescheme(topology_st):
"""
Test deleting of the storage scheme.
"""

default = topology_st.standalone.config.get_attr_val('passwordStorageScheme')
# Change it
topology_st.standalone.config.set('passwordStorageScheme', 'CLEAR')
# Now delete it
> topology_st.standalone.config.remove('passwordStorageScheme', None)

/export/tests/tickets/ticket48961_test.py:28:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
/usr/local/lib/python3.8/site-packages/lib389/_mapped_object.py:316: in remove
self.set(key, value, action=ldap.MOD_DELETE)
/usr/local/lib/python3.8/site-packages/lib389/_mapped_object.py:446: in set
return self._instance.modify_ext_s(self._dn, [(action, key, value)],
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:613: in modify_ext_s
resp_type, resp_data, resp_msgid, resp_ctrls = self.result3(msgid,all=1,timeout=self.timeout)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:764: in result3
resp_type, resp_data, resp_msgid, decoded_resp_ctrls, retoid, retval = self.result4(
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:774: in result4
ldap_result = self._ldap_call(self._l.result4,msgid,all,timeout,add_ctrls,add_intermediates,add_extop)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:340: in _ldap_call
reraise(exc_type, exc_value, exc_traceback)
/usr/local/lib64/python3.8/site-packages/ldap/compat.py:46: in reraise
raise exc_value
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

self = <lib389.DirSrv object at 0x7ff7f1d03fd0>
func = <built-in method result4 of LDAP object at 0x7ff7f16b7240>
args = (5, 1, -1, 0, 0, 0), kwargs = {}, diagnostic_message_success = None
exc_type = None, exc_value = None, exc_traceback = None

def _ldap_call(self,func,*args,**kwargs):
"""
Wrapper method mainly for serializing calls into OpenLDAP libs
and trace logs
"""
self._ldap_object_lock.acquire()
if __debug__:
if self._trace_level>=1:
self._trace_file.write('*** %s %s - %s\n%s\n' % (
repr(self),
self._uri,
'.'.join((self.__class__.__name__,func.__name__)),
pprint.pformat((args,kwargs))
))
if self._trace_level>=9:
traceback.print_stack(limit=self._trace_stack_limit,file=self._trace_file)
diagnostic_message_success = None
try:
try:
> result = func(*args,**kwargs)
E ldap.OPERATIONS_ERROR: {'msgtype': 103, 'msgid': 5, 'result': 1, 'desc': 'Operations error', 'ctrls': [], 'info': 'passwordStorageScheme: deleting the value is not allowed.'}

/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:324: OPERATIONS_ERROR
-------------------------------Captured log setup-------------------------------
INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 38901, 'ldap-secureport': 63601, 'server-id': 'standalone1', 'suffix': 'dc=example,dc=com'} was created.

topology_st = <lib389.topologies.TopologyMain object at 0x7ff7f16ae2b0>

def test_ticket48961_deleteall(topology_st):
"""
Test that we can delete all valid attrs, and that a few are rejected.
"""
attr_to_test = {
'nsslapd-listenhost': 'localhost',
'nsslapd-securelistenhost': 'localhost',
'nsslapd-allowed-sasl-mechanisms': 'GSSAPI',
'nsslapd-svrtab': 'Some bogus data', # This one could reset?
}
attr_to_fail = {
# These are the values that should always be dn dse.ldif too
'nsslapd-localuser': 'dirsrv',
'nsslapd-defaultnamingcontext': 'dc=example,dc=com', # Can't delete
'nsslapd-accesslog': '/opt/dirsrv/var/log/dirsrv/slapd-standalone/access',
'nsslapd-auditlog': '/opt/dirsrv/var/log/dirsrv/slapd-standalone/audit',
'nsslapd-errorlog': '/opt/dirsrv/var/log/dirsrv/slapd-standalone/errors',
'nsslapd-tmpdir': '/tmp',
'nsslapd-rundir': '/opt/dirsrv/var/run/dirsrv',
'nsslapd-bakdir': '/opt/dirsrv/var/lib/dirsrv/slapd-standalone/bak',
'nsslapd-certdir': '/opt/dirsrv/etc/dirsrv/slapd-standalone',
'nsslapd-instancedir': '/opt/dirsrv/lib/dirsrv/slapd-standalone',
'nsslapd-ldifdir': '/opt/dirsrv/var/lib/dirsrv/slapd-standalone/ldif',
'nsslapd-lockdir': '/opt/dirsrv/var/lock/dirsrv/slapd-standalone',
'nsslapd-schemadir': '/opt/dirsrv/etc/dirsrv/slapd-standalone/schema',
'nsslapd-workingdir': '/opt/dirsrv/var/log/dirsrv/slapd-standalone',
'nsslapd-localhost': 'localhost.localdomain',
# These can't be reset, but might be in dse.ldif. Probably in libglobs.
'nsslapd-certmap-basedn': 'cn=certmap,cn=config',
'nsslapd-port': '38931', # Can't delete
'nsslapd-secureport': '636', # Can't delete
'nsslapd-conntablesize': '1048576',
'nsslapd-rootpw': '{SSHA512}...',
# These are hardcoded server magic.
'nsslapd-hash-filters': 'off', # Can't delete
'nsslapd-requiresrestart': 'cn=config:nsslapd-port', # Can't change
'nsslapd-plugin': 'cn=case ignore string syntax,cn=plugins,cn=config', # Can't change
'nsslapd-privatenamespaces': 'cn=schema', # Can't change
'nsslapd-allowed-to-delete-attrs': 'None', # Can't delete
'nsslapd-accesslog-list': 'List!', # Can't delete
'nsslapd-auditfaillog-list': 'List!',
'nsslapd-auditlog-list': 'List!',
'nsslapd-errorlog-list': 'List!',
'nsslapd-config': 'cn=config',
'nsslapd-versionstring': '389-Directory/1.3.6.0',
'objectclass': '',
'cn': '',
# These are the odd values
'nsslapd-backendconfig': 'cn=config,cn=userRoot,cn=ldbm database,cn=plugins,cn=config', # Doesn't exist?
'nsslapd-betype': 'ldbm database', # Doesn't exist?
'nsslapd-connection-buffer': 1, # Has an ldap problem
'nsslapd-malloc-mmap-threshold': '-10', # Defunct anyway
'nsslapd-malloc-mxfast': '-10',
'nsslapd-malloc-trim-threshold': '-10',
'nsslapd-referralmode': '',
'nsslapd-saslpath': '',
'passwordadmindn': '',
}

> config_entry = topology_st.standalone.config.raw_entry()

/export/tests/tickets/ticket48961_test.py:101:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

self = <lib389.config.Config object at 0x7ff7f1d03d00>, name = 'raw_entry'

def __getattr__(self, name):
"""This enables a bit of magic to allow us to wrap any function ending with
_json to it's form without json, then transformed. It means your function
*must* return it's values as a dict of:

{ attr : [val, val, ...], attr : [], ... }
to be supported.
"""

if (name.endswith('_json')):
int_name = name.replace('_json', '')
pfunc = partial(self._jsonify, getattr(self, int_name))
return pfunc
else:
> raise AttributeError("'%s' object has no attribute '%s'" % (self.__class__.__name__, name))
E AttributeError: 'Config' object has no attribute 'raw_entry'

/usr/local/lib/python3.8/site-packages/lib389/_mapped_object.py:199: AttributeError

topology = <tests.tickets.ticket48973_test.TopologyStandalone object at 0x7ff7f1cd7be0>

def test_ticket48973_homeDirectory_indexing(topology):
"""
Check that homedirectory is indexed with syntax (ces)
- triggers index
- no failure on index
- do a search indexed with exact value (ces) and no default_mr_indexer_create warning
- do a search indexed with uppercase value (ces) and no default_mr_indexer_create warning
"""
entry_ext = 1

try:
ent = topology.standalone.getEntry(HOMEDIRECTORY_INDEX, ldap.SCOPE_BASE)
except ldap.NO_SUCH_OBJECT:
topology.standalone.add_s(Entry((HOMEDIRECTORY_INDEX, {
'objectclass': "top nsIndex".split(),
'cn': HOMEDIRECTORY_CN,
'nsSystemIndex': 'false',
'nsIndexType': 'eq'})))

args = {TASK_WAIT: True}
topology.standalone.tasks.reindex(suffix=SUFFIX, attrname='homeDirectory', args=args)

log.info("Check indexing succeeded with no specified matching rule")
assert not _find_first_indexing_failure(topology, "unknown or invalid matching rule")
assert not _find_first_indexing_failure(topology, "default_mr_indexer_create: warning")
assert not _find_first_indexing_failure(topology, "default_mr_indexer_create - Plugin .* does not handle")

_check_entry(topology, filterHead="homeDirectory", filterValueUpper=False, entry_ext=entry_ext,found=True, indexed=True)

> _check_entry(topology, filterHead="homeDirectory:caseExactIA5Match:", filterValueUpper=False, entry_ext=entry_ext, found=True, indexed=False)

/export/tests/tickets/ticket48973_test.py:251:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

topology = <tests.tickets.ticket48973_test.TopologyStandalone object at 0x7ff7f1cd7be0>
filterHead = 'homeDirectory:caseExactIA5Match:', filterValueUpper = False
entry_ext = 1, found = True, indexed = False

def _check_entry(topology, filterHead=None, filterValueUpper=False, entry_ext=None, found=False, indexed=False):
# Search with CES with exact value -> find an entry + indexed
if filterValueUpper:
homehead = HOMEHEAD.upper()
else:
homehead = HOMEHEAD
searchedHome = "%s%d" % (homehead, entry_ext)
Filter = "(%s=%s)" % (filterHead, searchedHome)
log.info("Search %s" % Filter)
ents = topology.standalone.search_s(SUFFIX, ldap.SCOPE_SUBTREE, Filter)
if found:
assert len(ents) == 1
assert ents[0].hasAttr('homedirectory')
valueHome = ensure_bytes("%s%d" % (HOMEHEAD, entry_ext))
assert valueHome in ents[0].getValues('homedirectory')
else:
assert len(ents) == 0

result = _find_next_notes(topology, Filter)
log.info("result=%s" % result)
if indexed:
assert not "notes=U" in result
else:
> assert "notes=U" in result
E AssertionError: assert 'notes=U' in '[07/Oct/2020:23:06:58.752700594 -0400] conn=1 op=2 RESULT err=0 tag=101 nentries=1 wtime=0.000347584 optime=0.005291922 etime=0.005633152\n'

/export/tests/tickets/ticket48973_test.py:188: AssertionError
-------------------------------Captured log call--------------------------------
INFO  lib389:tasks.py:795 Index task index_attrs_10072020_230652 completed successfully INFO  tests.tickets.ticket48973_test:ticket48973_test.py:244 Check indexing succeeded with no specified matching rule INFO  tests.tickets.ticket48973_test:ticket48973_test.py:173 Search (homeDirectory=/home/xyz_1) INFO  tests.tickets.ticket48973_test:ticket48973_test.py:184 result=[07/Oct/2020:23:06:54.828217386 -0400] conn=1 op=10 RESULT err=0 tag=101 nentries=1 wtime=0.000248570 optime=0.000339838 etime=0.000585120 INFO  tests.tickets.ticket48973_test:ticket48973_test.py:173 Search (homeDirectory:caseExactIA5Match:=/home/xyz_1) INFO  tests.tickets.ticket48973_test:ticket48973_test.py:184 result=[07/Oct/2020:23:06:58.752700594 -0400] conn=1 op=2 RESULT err=0 tag=101 nentries=1 wtime=0.000347584 optime=0.005291922 etime=0.005633152

T = <lib389.topologies.TopologyMain object at 0x7ff7f2b705e0>

def test_ticket49020(T):
A = T.ms['master1']
B = T.ms['master2']
C = T.ms['master3']

A.enableReplLogging()
B.enableReplLogging()
C.enableReplLogging()

AtoB = A.agreement.list(suffix=DEFAULT_SUFFIX)[0].dn
AtoC = A.agreement.list(suffix=DEFAULT_SUFFIX)[1].dn
CtoB = C.agreement.list(suffix=DEFAULT_SUFFIX)[1].dn

A.agreement.pause(AtoB)
C.agreement.pause(CtoB)
time.sleep(5)
name = "userX"
dn = "cn={},{}".format(name, DEFAULT_SUFFIX)
A.add_s(Entry((dn, {'objectclass': "top person".split(),
'sn': name,'cn': name})))

A.agreement.init(DEFAULT_SUFFIX, socket.gethostname(), PORT_MASTER_3)
time.sleep(5)
for i in range(1,11):
name = "userY{}".format(i)
dn = "cn={},{}".format(name, DEFAULT_SUFFIX)
A.add_s(Entry((dn, {'objectclass': "top person".split(),
'sn': name,'cn': name})))
time.sleep(5)
C.agreement.resume(CtoB)

time.sleep(5)
A_entries = A.search_s(DEFAULT_SUFFIX, ldap.SCOPE_SUBTREE,
'(objectClass=person)')
B_entries = B.search_s(DEFAULT_SUFFIX, ldap.SCOPE_SUBTREE,
'(objectClass=person)')
C_entries = C.search_s(DEFAULT_SUFFIX, ldap.SCOPE_SUBTREE,
'(objectClass=person)')

assert len(A_entries) == len(C_entries)
> assert len(B_entries) == len(A_entries) - 11
E assert 10 == 0
E +10
E -0

/export/tests/tickets/ticket49020_test.py:66: AssertionError
-------------------------------Captured log setup-------------------------------
INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 39001, 'ldap-secureport': 63701, 'server-id': 'master1', 'suffix': 'dc=example,dc=com'} was created. INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 39002, 'ldap-secureport': 63702, 'server-id': 'master2', 'suffix': 'dc=example,dc=com'} was created. INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 39003, 'ldap-secureport': 63703, 'server-id': 'master3', 'suffix': 'dc=example,dc=com'} was created. INFO  lib389.topologies:topologies.py:148 Creating replication topology. INFO  lib389.topologies:topologies.py:162 Joining master master2 to master1 ... INFO  lib389.replica:replica.py:2068 SUCCESS: bootstrap to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 completed INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is was created INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is was created INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is NOT working (expect 9b6448b2-c541-4746-b246-98d4fda0ee1f / got description=None) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 7f9ecdfa-43b2-40cd-bd54-3b3830e992d0 / got description=9b6448b2-c541-4746-b246-98d4fda0ee1f) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is working INFO  lib389.replica:replica.py:2137 SUCCESS: joined master from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 INFO  lib389.topologies:topologies.py:162 Joining master master3 to master1 ... INFO  lib389.replica:replica.py:2068 SUCCESS: bootstrap to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 completed INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 is was created INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is was created INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 is NOT working (expect a309d113-7047-4145-9545-4079b2e588eb / got description=7f9ecdfa-43b2-40cd-bd54-3b3830e992d0) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 282ae7af-38bf-4a9e-99fe-55f3d7edfc56 / got description=a309d113-7047-4145-9545-4079b2e588eb) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is working INFO  lib389.replica:replica.py:2137 SUCCESS: joined master from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 INFO  lib389.topologies:topologies.py:170 Ensuring master master1 to master2 ... INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 already exists INFO  lib389.topologies:topologies.py:170 Ensuring master master1 to master3 ... INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 already exists INFO  lib389.topologies:topologies.py:170 Ensuring master master2 to master1 ... INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 already exists INFO  lib389.topologies:topologies.py:170 Ensuring master master2 to master3 ... INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 is was created INFO  lib389.topologies:topologies.py:170 Ensuring master master3 to master1 ... INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 already exists INFO  lib389.topologies:topologies.py:170 Ensuring master master3 to master2 ... INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is was created
-------------------------------Captured log call--------------------------------
INFO  lib389:agreement.py:1194 Pausing replication cn=002,cn=replica,cn=dc\3Dexample\2Cdc\3Dcom,cn=mapping tree,cn=config INFO  lib389:agreement.py:1194 Pausing replication cn=002,cn=replica,cn=dc\3Dexample\2Cdc\3Dcom,cn=mapping tree,cn=config INFO  lib389:agreement.py:1169 Starting total init cn=003,cn=replica,cn=dc\3Dexample\2Cdc\3Dcom,cn=mapping tree,cn=config INFO  lib389:agreement.py:1220 Resuming replication cn=002,cn=replica,cn=dc\3Dexample\2Cdc\3Dcom,cn=mapping tree,cn=config

topology_m2 = <lib389.topologies.TopologyMain object at 0x7ff7f169bca0>

def test_ticket49073(topology_m2):
"""Write your replication test here.

To access each DirSrv instance use: topology_m2.ms["master1"], topology_m2.ms["master2"],
..., topology_m2.hub1, ..., topology_m2.consumer1,...

Also, if you need any testcase initialization,
please, write additional fixture for that(include finalizer).
"""
topology_m2.ms["master1"].plugins.enable(name=PLUGIN_MEMBER_OF)
topology_m2.ms["master1"].restart(timeout=10)
topology_m2.ms["master2"].plugins.enable(name=PLUGIN_MEMBER_OF)
topology_m2.ms["master2"].restart(timeout=10)

# Configure fractional to prevent total init to send memberof
ents = topology_m2.ms["master1"].agreement.list(suffix=SUFFIX)
assert len(ents) == 1
log.info('update %s to add nsDS5ReplicatedAttributeListTotal' % ents[0].dn)
> topology_m2.ms["master1"].modify_s(ents[0].dn,
[(ldap.MOD_REPLACE,
'nsDS5ReplicatedAttributeListTotal',
'(objectclass=*) $ EXCLUDE '),
(ldap.MOD_REPLACE,
'nsDS5ReplicatedAttributeList',
'(objectclass=*) $ EXCLUDE memberOf')])

/export/tests/tickets/ticket49073_test.py:97:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:640: in modify_s
return self.modify_ext_s(dn,modlist,None,None)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:612: in modify_ext_s
msgid = self.modify_ext(dn,modlist,serverctrls,clientctrls)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:609: in modify_ext
return self._ldap_call(self._l.modify_ext,dn,modlist,RequestControlTuples(serverctrls),RequestControlTuples(clientctrls))
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

self = <lib389.DirSrv object at 0x7ff7f169b6a0>
func = <built-in method modify_ext of LDAP object at 0x7ff7f12abbd0>
args = ('cn=002,cn=replica,cn=dc\\3Dexample\\2Cdc\\3Dcom,cn=mapping tree,cn=config', [(2, 'nsDS5ReplicatedAttributeListTotal', '(objectclass=*) $ EXCLUDE '), (2, 'nsDS5ReplicatedAttributeList', '(objectclass=*) $ EXCLUDE memberOf')], None, None)
kwargs = {}, diagnostic_message_success = None

def _ldap_call(self,func,*args,**kwargs):
"""
Wrapper method mainly for serializing calls into OpenLDAP libs
and trace logs
"""
self._ldap_object_lock.acquire()
if __debug__:
if self._trace_level>=1:
self._trace_file.write('*** %s %s - %s\n%s\n' % (
repr(self),
self._uri,
'.'.join((self.__class__.__name__,func.__name__)),
pprint.pformat((args,kwargs))
))
if self._trace_level>=9:
traceback.print_stack(limit=self._trace_stack_limit,file=self._trace_file)
diagnostic_message_success = None
try:
try:
> result = func(*args,**kwargs)
E TypeError: ('Tuple_to_LDAPMod(): expected a byte string in the list', '(')

/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:324: TypeError
-------------------------------Captured log setup-------------------------------
INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 39001, 'ldap-secureport': 63701, 'server-id': 'master1', 'suffix': 'dc=example,dc=com'} was created. INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 39002, 'ldap-secureport': 63702, 'server-id': 'master2', 'suffix': 'dc=example,dc=com'} was created. INFO  lib389.topologies:topologies.py:148 Creating replication topology. INFO  lib389.topologies:topologies.py:162 Joining master master2 to master1 ... INFO  lib389.replica:replica.py:2068 SUCCESS: bootstrap to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 completed INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is was created INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is was created INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is NOT working (expect 9fedae3b-658e-4c1a-b92d-86017eaec348 / got description=None) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 2b6acf37-4ed2-40ed-a640-6ad0bca2b2de / got description=9fedae3b-658e-4c1a-b92d-86017eaec348) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is working INFO  lib389.replica:replica.py:2137 SUCCESS: joined master from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 INFO  lib389.topologies:topologies.py:170 Ensuring master master1 to master2 ... INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 already exists INFO  lib389.topologies:topologies.py:170 Ensuring master master2 to master1 ... INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 already exists
-------------------------------Captured log call--------------------------------
INFO  tests.tickets.ticket49073_test:ticket49073_test.py:96 update cn=002,cn=replica,cn=dc\3Dexample\2Cdc\3Dcom,cn=mapping tree,cn=config to add nsDS5ReplicatedAttributeListTotal

topology_m4 = <lib389.topologies.TopologyMain object at 0x7ff7f15c8910>

def test_ticket49180(topology_m4):

log.info('Running test_ticket49180...')

log.info('Check that replication works properly on all masters')
agmt_nums = {"master1": ("2", "3", "4"),
"master2": ("1", "3", "4"),
"master3": ("1", "2", "4"),
"master4": ("1", "2", "3")}

for inst_name, agmts in agmt_nums.items():
for num in agmts:
if not topology_m4.ms[inst_name].testReplication(DEFAULT_SUFFIX, topology_m4.ms["master{}".format(num)]):
log.fatal(
'test_replication: Replication is not working between {} and master {}.'.format(inst_name,
num))
assert False

# Disable master 4
log.info('test_clean: disable master 4...')
topology_m4.ms["master4"].replica.disableReplication(DEFAULT_SUFFIX)

# Remove the agreements from the other masters that point to master 4
remove_master4_agmts("test_clean", topology_m4)

# Cleanup - restore master 4
> restore_master4(topology_m4)

/export/tests/tickets/ticket49180_test.py:102:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
/export/tests/tickets/ticket49180_test.py:52: in restore_master4
repl.join_master(M1, M4)
/usr/local/lib/python3.8/site-packages/lib389/replica.py:2135: in join_master
self.test_replication(to_instance, from_instance)
/usr/local/lib/python3.8/site-packages/lib389/replica.py:2501: in test_replication
self.wait_for_replication(from_instance, to_instance, timeout)
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

self = <lib389.replica.ReplicationManager object at 0x7ff7f257b9d0>
from_instance = <lib389.DirSrv object at 0x7ff7f2577b50>
to_instance = <lib389.DirSrv object at 0x7ff7f15c8370>, timeout = 20

def wait_for_replication(self, from_instance, to_instance, timeout=20):
"""Wait for a replication event to occur from instance to instance. This
shows some point of synchronisation has occured.

:param from_instance: The instance whos state we we want to check from
:type from_instance: lib389.DirSrv
:param to_instance: The instance whos state we want to check matches from.
:type to_instance: lib389.DirSrv
:param timeout: Fail after timeout seconds.
:type timeout: int

"""
# Touch something then wait_for_replication.
from_groups = Groups(from_instance, basedn=self._suffix, rdn=None)
to_groups = Groups(to_instance, basedn=self._suffix, rdn=None)
from_group = from_groups.get('replication_managers')
to_group = to_groups.get('replication_managers')

change = str(uuid.uuid4())

from_group.replace('description', change)

for i in range(0, timeout):
desc = to_group.get_attr_val_utf8('description')
if change == desc:
self._log.info("SUCCESS: Replication from %s to %s is working" % (from_instance.ldapuri, to_instance.ldapuri))
return True
self._log.info("Retry: Replication from %s to %s is NOT working (expect %s / got description=%s)" % (from_instance.ldapuri, to_instance.ldapuri, change, desc))
time.sleep(1)
self._log.info("FAIL: Replication from %s to %s is NOT working (expect %s / got description=%s)" % (from_instance.ldapuri, to_instance.ldapuri, change, desc))
> raise Exception("Replication did not sync in time!")
E Exception: Replication did not sync in time!

/usr/local/lib/python3.8/site-packages/lib389/replica.py:2485: Exception
-------------------------------Captured log setup-------------------------------
INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 39001, 'ldap-secureport': 63701, 'server-id': 'master1', 'suffix': 'dc=example,dc=com'} was created. INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 39002, 'ldap-secureport': 63702, 'server-id': 'master2', 'suffix': 'dc=example,dc=com'} was created. INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 39003, 'ldap-secureport': 63703, 'server-id': 'master3', 'suffix': 'dc=example,dc=com'} was created. INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 39004, 'ldap-secureport': 63704, 'server-id': 'master4', 'suffix': 'dc=example,dc=com'} was created. INFO  lib389.topologies:topologies.py:148 Creating replication topology. INFO  lib389.topologies:topologies.py:162 Joining master master2 to master1 ... INFO  lib389.replica:replica.py:2068 SUCCESS: bootstrap to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 completed INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is was created INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is was created INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is NOT working (expect 4e627135-7a2f-4d66-a3a3-4eb51ea40d9b / got description=None) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect b205570d-922a-4ad7-9ffa-832a20e7bc9a / got description=4e627135-7a2f-4d66-a3a3-4eb51ea40d9b) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is working INFO  lib389.replica:replica.py:2137 SUCCESS: joined master from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 INFO  lib389.topologies:topologies.py:162 Joining master master3 to master1 ... INFO  lib389.replica:replica.py:2068 SUCCESS: bootstrap to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 completed INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 is was created INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is was created INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 is NOT working (expect df12fc94-9e09-4f4e-b622-eb821c79797e / got description=b205570d-922a-4ad7-9ffa-832a20e7bc9a) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 49f1ff8a-7812-4564-a394-7b398701a9d3 / got description=df12fc94-9e09-4f4e-b622-eb821c79797e) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is working INFO  lib389.replica:replica.py:2137 SUCCESS: joined master from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 INFO  lib389.topologies:topologies.py:162 Joining master master4 to master1 ... INFO  lib389.replica:replica.py:2068 SUCCESS: bootstrap to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 completed INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 is was created INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is was created INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 is NOT working (expect 6ac236b0-a363-49f3-9847-66b050559346 / got description=49f1ff8a-7812-4564-a394-7b398701a9d3) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 99eb8b70-c91f-4a81-9b83-25e47f3e1423 / got description=6ac236b0-a363-49f3-9847-66b050559346) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is working INFO  lib389.replica:replica.py:2137 SUCCESS: joined master from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 INFO  lib389.topologies:topologies.py:170 Ensuring master master1 to master2 ... INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 already exists INFO  lib389.topologies:topologies.py:170 Ensuring master master1 to master3 ... INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 already exists INFO  lib389.topologies:topologies.py:170 Ensuring master master1 to master4 ... INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 already exists INFO  lib389.topologies:topologies.py:170 Ensuring master master2 to master1 ... INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 already exists INFO  lib389.topologies:topologies.py:170 Ensuring master master2 to master3 ... INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 is was created INFO  lib389.topologies:topologies.py:170 Ensuring master master2 to master4 ... INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 is was created INFO  lib389.topologies:topologies.py:170 Ensuring master master3 to master1 ... INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 already exists INFO  lib389.topologies:topologies.py:170 Ensuring master master3 to master2 ... INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is was created INFO  lib389.topologies:topologies.py:170 Ensuring master master3 to master4 ... INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 is was created INFO  lib389.topologies:topologies.py:170 Ensuring master master4 to master1 ... INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 already exists INFO  lib389.topologies:topologies.py:170 Ensuring master master4 to master2 ... INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is was created INFO  lib389.topologies:topologies.py:170 Ensuring master master4 to master3 ... INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 is was created
-------------------------------Captured log call--------------------------------
INFO  tests.tickets.ticket49180_test:ticket49180_test.py:78 Running test_ticket49180... INFO  tests.tickets.ticket49180_test:ticket49180_test.py:80 Check that replication works properly on all masters INFO  tests.tickets.ticket49180_test:ticket49180_test.py:95 test_clean: disable master 4... INFO  tests.tickets.ticket49180_test:ticket49180_test.py:30 test_clean: remove all the agreements to master 4... INFO  lib389:agreement.py:1095 Agreement (cn=004,cn=replica,cn=dc\3Dexample\2Cdc\3Dcom,cn=mapping tree,cn=config) was successfully removed INFO  lib389:agreement.py:1095 Agreement (cn=004,cn=replica,cn=dc\3Dexample\2Cdc\3Dcom,cn=mapping tree,cn=config) was successfully removed INFO  lib389:agreement.py:1095 Agreement (cn=004,cn=replica,cn=dc\3Dexample\2Cdc\3Dcom,cn=mapping tree,cn=config) was successfully removed INFO  tests.tickets.ticket49180_test:ticket49180_test.py:46 Restoring master 4... INFO  lib389.replica:replica.py:2068 SUCCESS: bootstrap to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 completed INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 is was created INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is was created INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 is NOT working (expect 2c20cfe1-cef2-461f-b40b-a60cb121eec2 / got description=99eb8b70-c91f-4a81-9b83-25e47f3e1423) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 849cb7b8-ed12-4c0c-834b-99207412f48f / got description=2c20cfe1-cef2-461f-b40b-a60cb121eec2) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 849cb7b8-ed12-4c0c-834b-99207412f48f / got description=2c20cfe1-cef2-461f-b40b-a60cb121eec2) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 849cb7b8-ed12-4c0c-834b-99207412f48f / got description=2c20cfe1-cef2-461f-b40b-a60cb121eec2) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 849cb7b8-ed12-4c0c-834b-99207412f48f / got description=2c20cfe1-cef2-461f-b40b-a60cb121eec2) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 849cb7b8-ed12-4c0c-834b-99207412f48f / got description=2c20cfe1-cef2-461f-b40b-a60cb121eec2) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 849cb7b8-ed12-4c0c-834b-99207412f48f / got description=2c20cfe1-cef2-461f-b40b-a60cb121eec2) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 849cb7b8-ed12-4c0c-834b-99207412f48f / got description=2c20cfe1-cef2-461f-b40b-a60cb121eec2) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 849cb7b8-ed12-4c0c-834b-99207412f48f / got description=2c20cfe1-cef2-461f-b40b-a60cb121eec2) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 849cb7b8-ed12-4c0c-834b-99207412f48f / got description=2c20cfe1-cef2-461f-b40b-a60cb121eec2) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 849cb7b8-ed12-4c0c-834b-99207412f48f / got description=2c20cfe1-cef2-461f-b40b-a60cb121eec2) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 849cb7b8-ed12-4c0c-834b-99207412f48f / got description=2c20cfe1-cef2-461f-b40b-a60cb121eec2) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 849cb7b8-ed12-4c0c-834b-99207412f48f / got description=2c20cfe1-cef2-461f-b40b-a60cb121eec2) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 849cb7b8-ed12-4c0c-834b-99207412f48f / got description=2c20cfe1-cef2-461f-b40b-a60cb121eec2) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 849cb7b8-ed12-4c0c-834b-99207412f48f / got description=2c20cfe1-cef2-461f-b40b-a60cb121eec2) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 849cb7b8-ed12-4c0c-834b-99207412f48f / got description=2c20cfe1-cef2-461f-b40b-a60cb121eec2) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 849cb7b8-ed12-4c0c-834b-99207412f48f / got description=2c20cfe1-cef2-461f-b40b-a60cb121eec2) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 849cb7b8-ed12-4c0c-834b-99207412f48f / got description=2c20cfe1-cef2-461f-b40b-a60cb121eec2) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 849cb7b8-ed12-4c0c-834b-99207412f48f / got description=2c20cfe1-cef2-461f-b40b-a60cb121eec2) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 849cb7b8-ed12-4c0c-834b-99207412f48f / got description=2c20cfe1-cef2-461f-b40b-a60cb121eec2) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 849cb7b8-ed12-4c0c-834b-99207412f48f / got description=2c20cfe1-cef2-461f-b40b-a60cb121eec2) INFO  lib389.replica:replica.py:2484 FAIL: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 849cb7b8-ed12-4c0c-834b-99207412f48f / got description=2c20cfe1-cef2-461f-b40b-a60cb121eec2)

topo = <lib389.topologies.TopologyMain object at 0x7ff7f114da30>

def test_ticket49192(topo):
"""Trigger deadlock when removing suffix
"""

#
# Create a second suffix/backend
#
log.info('Creating second backend...')
> topo.standalone.backends.create(None, properties={
BACKEND_NAME: "Second_Backend",
'suffix': "o=hang.com",
})

/export/tests/tickets/ticket49192_test.py:35:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
/usr/local/lib/python3.8/site-packages/lib389/_mapped_object.py:1169: in create
return co.create(rdn, properties, self._basedn)
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

self = <lib389.backend.Backend object at 0x7ff7f116cd00>, dn = None
properties = {'name': 'Second_Backend', 'suffix': 'o=hang.com'}
basedn = 'cn=ldbm database,cn=plugins,cn=config'

def create(self, dn=None, properties=None, basedn=DN_LDBM):
"""Add a new backend entry, create mapping tree,
and, if requested, sample entries

:param dn: DN of the new entry
:type dn: str
:param properties: Attributes and parameters for the new entry
:type properties: dict
:param basedn: Base DN of the new entry
:type basedn: str

:returns: DSLdapObject of the created entry
"""

sample_entries = False
parent_suffix = False

# normalize suffix (remove spaces between comps)
if dn is not None:
dn_comps = ldap.dn.explode_dn(dn.lower())
dn = ",".join(dn_comps)

if properties is not None:
> suffix_dn = properties['nsslapd-suffix'].lower()
E KeyError: 'nsslapd-suffix'

/usr/local/lib/python3.8/site-packages/lib389/backend.py:585: KeyError
-------------------------------Captured log setup-------------------------------
INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 38901, 'ldap-secureport': 63601, 'server-id': 'standalone1', 'suffix': 'dc=example,dc=com'} was created.
-------------------------------Captured log call--------------------------------
INFO  tests.tickets.ticket49192_test:ticket49192_test.py:34 Creating second backend...

topo = <lib389.topologies.TopologyMain object at 0x7ff7f11c2c40>

def test_ticket49303(topo):
"""
Test the nsTLSAllowClientRenegotiation setting.
"""
sslport = SECUREPORT_STANDALONE1

log.info("Ticket 49303 - Allow disabling of SSL renegotiation")

# No value set, defaults to reneg allowed
enable_ssl(topo.standalone, sslport)
> assert try_reneg(HOST_STANDALONE1, sslport) is True
E AssertionError: assert False is True
E + where False = try_reneg('LOCALHOST', 63601)

/export/tests/tickets/ticket49303_test.py:88: AssertionError
-------------------------------Captured log setup-------------------------------
INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 38901, 'ldap-secureport': 63601, 'server-id': 'standalone1', 'suffix': 'dc=example,dc=com'} was created.
-------------------------------Captured log call--------------------------------
INFO  tests.tickets.ticket49303_test:ticket49303_test.py:84 Ticket 49303 - Allow disabling of SSL renegotiation

topo = <lib389.topologies.TopologyMain object at 0x7ff7f2a63a30>

def test_ticket49412(topo):
"""Specify a test case purpose or name here

:id: 4c7681ff-0511-4256-9589-bdcad84c13e6
:setup: Fill in set up configuration here
:steps:
1. Fill in test case steps here
2. And indent them like this (RST format requirement)
:expectedresults:
1. Fill in the result that is expected
2. For each test step
"""

M1 = topo.ms["master1"]

# wrong call with invalid value (should be str(60)
# that create replace with NULL value
# it should fail with UNWILLING_TO_PERFORM
try:
> M1.modify_s(CHANGELOG, [(ldap.MOD_REPLACE, MAXAGE_ATTR, 60),
(ldap.MOD_REPLACE, TRIMINTERVAL, 10)])

/export/tests/tickets/ticket49412_test.py:44:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:640: in modify_s
return self.modify_ext_s(dn,modlist,None,None)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:613: in modify_ext_s
resp_type, resp_data, resp_msgid, resp_ctrls = self.result3(msgid,all=1,timeout=self.timeout)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:764: in result3
resp_type, resp_data, resp_msgid, decoded_resp_ctrls, retoid, retval = self.result4(
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:774: in result4
ldap_result = self._ldap_call(self._l.result4,msgid,all,timeout,add_ctrls,add_intermediates,add_extop)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:340: in _ldap_call
reraise(exc_type, exc_value, exc_traceback)
/usr/local/lib64/python3.8/site-packages/ldap/compat.py:46: in reraise
raise exc_value
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

self = <lib389.DirSrv object at 0x7ff7f258c5e0>
func = <built-in method result4 of LDAP object at 0x7ff7f2a637b0>
args = (39, 1, -1, 0, 0, 0), kwargs = {}, diagnostic_message_success = None
exc_type = None, exc_value = None, exc_traceback = None

def _ldap_call(self,func,*args,**kwargs):
"""
Wrapper method mainly for serializing calls into OpenLDAP libs
and trace logs
"""
self._ldap_object_lock.acquire()
if __debug__:
if self._trace_level>=1:
self._trace_file.write('*** %s %s - %s\n%s\n' % (
repr(self),
self._uri,
'.'.join((self.__class__.__name__,func.__name__)),
pprint.pformat((args,kwargs))
))
if self._trace_level>=9:
traceback.print_stack(limit=self._trace_stack_limit,file=self._trace_file)
diagnostic_message_success = None
try:
try:
> result = func(*args,**kwargs)
E ldap.NO_SUCH_OBJECT: {'msgtype': 103, 'msgid': 39, 'result': 32, 'desc': 'No such object', 'ctrls': []}

/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:324: NO_SUCH_OBJECT
-------------------------------Captured log setup-------------------------------
INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 39001, 'ldap-secureport': 63701, 'server-id': 'master1', 'suffix': 'dc=example,dc=com'} was created. INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 39201, 'ldap-secureport': 63901, 'server-id': 'consumer1', 'suffix': 'dc=example,dc=com'} was created. INFO  lib389.topologies:topologies.py:148 Creating replication topology. INFO  lib389.topologies:topologies.py:175 Joining consumer consumer1 from master1 ... INFO  lib389.replica:replica.py:2068 SUCCESS: bootstrap to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39201 completed INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39201 is was created INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39201 is NOT working (expect 1696b040-6178-4c07-977b-cbea1449da2b / got description=None) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39201 is working INFO  lib389.replica:replica.py:2252 SUCCESS: joined consumer from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39201 INFO  lib389.topologies:topologies.py:180 Ensuring consumer consumer1 from master1 ... INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39201 already exists

topo = <lib389.topologies.TopologyMain object at 0x7ff7f1e097f0>

def test_ticket_49463(topo):
"""Specify a test case purpose or name here

:id: 2a68e8be-387d-4ac7-9452-1439e8483c13
:setup: Fill in set up configuration here
:steps:
1. Enable fractional replication
2. Enable replication logging
3. Check that replication is working fine
4. Generate skipped updates to create keep alive entries
5. Remove M3 from the topology
6. issue cleanAllRuv FORCE that will run on M1 then propagated M2 and M4
7. Check that Number DEL keep alive '3' is <= 1
8. Check M1 is the originator of cleanAllRuv and M2/M4 the propagated ones
9. Check replication M1,M2 and M4 can recover
10. Remove M4 from the topology
11. Issue cleanAllRuv not force while M2 is stopped (that hangs the cleanAllRuv)
12. Check that nsds5ReplicaCleanRUV is correctly encoded on M1 (last value: 1)
13. Check that nsds5ReplicaCleanRUV encoding survives M1 restart
14. Check that nsds5ReplicaCleanRUV encoding is valid on M2 (last value: 0)
15. Check that (for M4 cleanAllRUV) M1 is Originator and M2 propagation
:expectedresults:
1. No report of failure when the RUV is updated
"""

# Step 1 - Configure fractional (skip telephonenumber) replication
M1 = topo.ms["master1"]
M2 = topo.ms["master2"]
M3 = topo.ms["master3"]
M4 = topo.ms["master4"]
repl = ReplicationManager(DEFAULT_SUFFIX)
fractional_server_to_replica(M1, M2)
fractional_server_to_replica(M1, M3)
fractional_server_to_replica(M1, M4)

fractional_server_to_replica(M2, M1)
fractional_server_to_replica(M2, M3)
fractional_server_to_replica(M2, M4)

fractional_server_to_replica(M3, M1)
fractional_server_to_replica(M3, M2)
fractional_server_to_replica(M3, M4)

fractional_server_to_replica(M4, M1)
fractional_server_to_replica(M4, M2)
fractional_server_to_replica(M4, M3)

# Step 2 - enable internal op logging and replication debug
for i in (M1, M2, M3, M4):
i.config.loglevel(vals=[256 + 4], service='access')
i.config.loglevel(vals=[LOG_REPLICA, LOG_DEFAULT], service='error')

# Step 3 - Check that replication is working fine
add_user(M1, 11, desc="add to M1")
add_user(M2, 21, desc="add to M2")
add_user(M3, 31, desc="add to M3")
add_user(M4, 41, desc="add to M4")

for i in (M1, M2, M3, M4):
for j in (M1, M2, M3, M4):
if i == j:
continue
repl.wait_for_replication(i, j)

# Step 4 - Generate skipped updates to create keep alive entries
for i in (M1, M2, M3, M4):
cn = '%s_%d' % (USER_CN, 11)
dn = 'uid=%s,ou=People,%s' % (cn, SUFFIX)
users = UserAccount(i, dn)
for j in range(110):
users.set('telephoneNumber', str(j))

# Step 5 - Remove M3 from the topology
M3.stop()
M1.agreement.delete(suffix=SUFFIX, consumer_host=M3.host, consumer_port=M3.port)
M2.agreement.delete(suffix=SUFFIX, consumer_host=M3.host, consumer_port=M3.port)
M4.agreement.delete(suffix=SUFFIX, consumer_host=M3.host, consumer_port=M3.port)

# Step 6 - Then issue cleanAllRuv FORCE that will run on M1, M2 and M4
M1.tasks.cleanAllRUV(suffix=SUFFIX, replicaid='3',
force=True, args={TASK_WAIT: True})

# Step 7 - Count the number of received DEL of the keep alive 3
for i in (M1, M2, M4):
i.restart()
regex = re.compile(".*DEL dn=.cn=repl keep alive 3.*")
for i in (M1, M2, M4):
count = count_pattern_accesslog(M1, regex)
log.debug("count on %s = %d" % (i, count))

# check that DEL is replicated once (If DEL is kept in the fix)
# check that DEL is is not replicated (If DEL is finally no long done in the fix)
assert ((count == 1) or (count == 0))

# Step 8 - Check that M1 is Originator of cleanAllRuv and M2, M4 propagation
regex = re.compile(".*Original task deletes Keep alive entry .3.*")
assert pattern_errorlog(M1, regex)

regex = re.compile(".*Propagated task does not delete Keep alive entry .3.*")
assert pattern_errorlog(M2, regex)
assert pattern_errorlog(M4, regex)

# Step 9 - Check replication M1,M2 and M4 can recover
add_user(M1, 12, desc="add to M1")
add_user(M2, 22, desc="add to M2")
for i in (M1, M2, M4):
for j in (M1, M2, M4):
if i == j:
continue
repl.wait_for_replication(i, j)

# Step 10 - Remove M4 from the topology
M4.stop()
M1.agreement.delete(suffix=SUFFIX, consumer_host=M4.host, consumer_port=M4.port)
M2.agreement.delete(suffix=SUFFIX, consumer_host=M4.host, consumer_port=M4.port)

# Step 11 - Issue cleanAllRuv not force while M2 is stopped (that hangs the cleanAllRuv)
M2.stop()
M1.tasks.cleanAllRUV(suffix=SUFFIX, replicaid='4',
force=False, args={TASK_WAIT: False})

# Step 12
# CleanAllRuv is hanging waiting for M2 to restart
# Check that nsds5ReplicaCleanRUV is correctly encoded on M1
replicas = Replicas(M1)
replica = replicas.list()[0]
time.sleep(0.5)
replica.present('nsds5ReplicaCleanRUV')
log.info("M1: nsds5ReplicaCleanRUV=%s" % replica.get_attr_val_utf8('nsds5replicacleanruv'))
regex = re.compile("^4:.*:no:1$")
> assert regex.match(replica.get_attr_val_utf8('nsds5replicacleanruv'))
E AssertionError: assert None
E + where None = <built-in method match of re.Pattern object at 0x7ff7f2f6a730>('4:no:1:dc=example,dc=com')
E + where <built-in method match of re.Pattern object at 0x7ff7f2f6a730> = re.compile('^4:.*:no:1$').match
E + and '4:no:1:dc=example,dc=com' = <bound method DSLdapObject.get_attr_val_utf8 of <lib389.replica.Replica object at 0x7ff7f28da550>>('nsds5replicacleanruv')
E + where <bound method DSLdapObject.get_attr_val_utf8 of <lib389.replica.Replica object at 0x7ff7f28da550>> = <lib389.replica.Replica object at 0x7ff7f28da550>.get_attr_val_utf8

/export/tests/tickets/ticket49463_test.py:188: AssertionError
-------------------------------Captured log setup-------------------------------
INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 39001, 'ldap-secureport': 63701, 'server-id': 'master1', 'suffix': 'dc=example,dc=com'} was created. INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 39002, 'ldap-secureport': 63702, 'server-id': 'master2', 'suffix': 'dc=example,dc=com'} was created. INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 39003, 'ldap-secureport': 63703, 'server-id': 'master3', 'suffix': 'dc=example,dc=com'} was created. INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 39004, 'ldap-secureport': 63704, 'server-id': 'master4', 'suffix': 'dc=example,dc=com'} was created. INFO  lib389.topologies:topologies.py:148 Creating replication topology. INFO  lib389.topologies:topologies.py:162 Joining master master2 to master1 ... INFO  lib389.replica:replica.py:2068 SUCCESS: bootstrap to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 completed INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is was created INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is was created INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is NOT working (expect 006d2911-f0e3-434e-b0f7-a21168bf456f / got description=None) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 7da7707d-e45e-4571-9b4e-124ff0f576ad / got description=006d2911-f0e3-434e-b0f7-a21168bf456f) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is working INFO  lib389.replica:replica.py:2137 SUCCESS: joined master from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 INFO  lib389.topologies:topologies.py:162 Joining master master3 to master1 ... INFO  lib389.replica:replica.py:2068 SUCCESS: bootstrap to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 completed INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 is was created INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is was created INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 is NOT working (expect bfd931d5-af7c-4c5a-aa2c-650f9caeaba4 / got description=7da7707d-e45e-4571-9b4e-124ff0f576ad) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 3e902fe3-f462-4730-9c7d-3ab3ea309dda / got description=bfd931d5-af7c-4c5a-aa2c-650f9caeaba4) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is working INFO  lib389.replica:replica.py:2137 SUCCESS: joined master from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 INFO  lib389.topologies:topologies.py:162 Joining master master4 to master1 ... INFO  lib389.replica:replica.py:2068 SUCCESS: bootstrap to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 completed INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 is was created INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is was created INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 is NOT working (expect 00476ec3-1b11-4a44-bad8-9961b2786260 / got description=3e902fe3-f462-4730-9c7d-3ab3ea309dda) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect dd138245-6cb5-4024-9038-e5b9d7798f49 / got description=00476ec3-1b11-4a44-bad8-9961b2786260) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is working INFO  lib389.replica:replica.py:2137 SUCCESS: joined master from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 INFO  lib389.topologies:topologies.py:170 Ensuring master master1 to master2 ... INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 already exists INFO  lib389.topologies:topologies.py:170 Ensuring master master1 to master3 ... INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 already exists INFO  lib389.topologies:topologies.py:170 Ensuring master master1 to master4 ... INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 already exists INFO  lib389.topologies:topologies.py:170 Ensuring master master2 to master1 ... INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 already exists INFO  lib389.topologies:topologies.py:170 Ensuring master master2 to master3 ... INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 is was created INFO  lib389.topologies:topologies.py:170 Ensuring master master2 to master4 ... INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 is was created INFO  lib389.topologies:topologies.py:170 Ensuring master master3 to master1 ... INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 already exists INFO  lib389.topologies:topologies.py:170 Ensuring master master3 to master2 ... INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is was created INFO  lib389.topologies:topologies.py:170 Ensuring master master3 to master4 ... INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 is was created INFO  lib389.topologies:topologies.py:170 Ensuring master master4 to master1 ... INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 already exists INFO  lib389.topologies:topologies.py:170 Ensuring master master4 to master2 ... INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is was created INFO  lib389.topologies:topologies.py:170 Ensuring master master4 to master3 ... INFO  lib389.replica:replica.py:2349 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 is was created
-------------------------------Captured log call--------------------------------
INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 already exists INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 already exists INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 already exists INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 already exists INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 already exists INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 already exists INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 already exists INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 already exists INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 already exists INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 already exists INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 already exists INFO  lib389.replica:replica.py:2322 SUCCESS: Agreement from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 already exists INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is NOT working (expect bb60e5b2-9256-46d5-aebe-14fd1089bbe6 / got description=dd138245-6cb5-4024-9038-e5b9d7798f49) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 is NOT working (expect da366563-a5ca-492e-b662-b27ad2d7d6ef / got description=bb60e5b2-9256-46d5-aebe-14fd1089bbe6) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 is NOT working (expect f255feb7-dcfe-4f11-8d0d-59fb680958e8 / got description=da366563-a5ca-492e-b662-b27ad2d7d6ef) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 78a1c924-c517-45e8-a574-07c174232cc4 / got description=f255feb7-dcfe-4f11-8d0d-59fb680958e8) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 78a1c924-c517-45e8-a574-07c174232cc4 / got description=f255feb7-dcfe-4f11-8d0d-59fb680958e8) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 is NOT working (expect 8e8082cd-2eff-4f19-b76b-83e9b62b17e5 / got description=f255feb7-dcfe-4f11-8d0d-59fb680958e8) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 is NOT working (expect 8e8082cd-2eff-4f19-b76b-83e9b62b17e5 / got description=78a1c924-c517-45e8-a574-07c174232cc4) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 is NOT working (expect f89e56c2-678f-47fe-8beb-c3189373edb0 / got description=8e8082cd-2eff-4f19-b76b-83e9b62b17e5) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 2fbff247-40b1-46bd-b565-ae90c3c59ff4 / got description=8e8082cd-2eff-4f19-b76b-83e9b62b17e5) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 2fbff247-40b1-46bd-b565-ae90c3c59ff4 / got description=f89e56c2-678f-47fe-8beb-c3189373edb0) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is NOT working (expect 7a07ccf5-3cc1-4cd6-a773-e755d3996273 / got description=2fbff247-40b1-46bd-b565-ae90c3c59ff4) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 is NOT working (expect ba6f260f-219b-4763-be32-11d51597d279 / got description=7a07ccf5-3cc1-4cd6-a773-e755d3996273) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 is NOT working (expect ba6f260f-219b-4763-be32-11d51597d279 / got description=7a07ccf5-3cc1-4cd6-a773-e755d3996273) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect a9775a6b-8506-4820-b672-c866417f5e21 / got description=ba6f260f-219b-4763-be32-11d51597d279) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is NOT working (expect 40aa60fa-77e1-43c9-ad90-d37f86edd659 / got description=a9775a6b-8506-4820-b672-c866417f5e21) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is NOT working (expect 40aa60fa-77e1-43c9-ad90-d37f86edd659 / got description=a9775a6b-8506-4820-b672-c866417f5e21) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is NOT working (expect 40aa60fa-77e1-43c9-ad90-d37f86edd659 / got description=a9775a6b-8506-4820-b672-c866417f5e21) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is NOT working (expect 40aa60fa-77e1-43c9-ad90-d37f86edd659 / got description=a9775a6b-8506-4820-b672-c866417f5e21) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 is NOT working (expect 30cdf20f-b48e-42b7-9574-f5e4508bc2dc / got description=40aa60fa-77e1-43c9-ad90-d37f86edd659) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39003 is working INFO  lib389:agreement.py:1095 Agreement (cn=003,cn=replica,cn=dc\3Dexample\2Cdc\3Dcom,cn=mapping tree,cn=config) was successfully removed INFO  lib389:agreement.py:1095 Agreement (cn=003,cn=replica,cn=dc\3Dexample\2Cdc\3Dcom,cn=mapping tree,cn=config) was successfully removed INFO  lib389:agreement.py:1095 Agreement (cn=003,cn=replica,cn=dc\3Dexample\2Cdc\3Dcom,cn=mapping tree,cn=config) was successfully removed INFO  lib389:tasks.py:1397 cleanAllRUV task (task-10072020_232923) completed successfully INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is NOT working (expect 7f4e4aa3-cf1a-4c18-bbd2-2a6c73eb0c05 / got description=30cdf20f-b48e-42b7-9574-f5e4508bc2dc) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is NOT working (expect 7f4e4aa3-cf1a-4c18-bbd2-2a6c73eb0c05 / got description=30cdf20f-b48e-42b7-9574-f5e4508bc2dc) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 is NOT working (expect 192ee50d-9a1b-4436-ba5c-be20019095d8 / got description=7f4e4aa3-cf1a-4c18-bbd2-2a6c73eb0c05) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 68dbf8eb-c62c-4356-a426-2d563f346e4a / got description=192ee50d-9a1b-4436-ba5c-be20019095d8) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 68dbf8eb-c62c-4356-a426-2d563f346e4a / got description=192ee50d-9a1b-4436-ba5c-be20019095d8) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 is NOT working (expect 98c3fe9a-2572-4cab-9cd6-4e74bdad2226 / got description=68dbf8eb-c62c-4356-a426-2d563f346e4a) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 is NOT working (expect 98c3fe9a-2572-4cab-9cd6-4e74bdad2226 / got description=68dbf8eb-c62c-4356-a426-2d563f346e4a) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 is NOT working (expect 98c3fe9a-2572-4cab-9cd6-4e74bdad2226 / got description=68dbf8eb-c62c-4356-a426-2d563f346e4a) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 495133d3-6e2b-421c-a4fa-e6f7e38bd413 / got description=98c3fe9a-2572-4cab-9cd6-4e74bdad2226) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is NOT working (expect 495133d3-6e2b-421c-a4fa-e6f7e38bd413 / got description=98c3fe9a-2572-4cab-9cd6-4e74bdad2226) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39001 is working INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is NOT working (expect ee84f135-998e-4b6e-aec9-c5a0438a486c / got description=495133d3-6e2b-421c-a4fa-e6f7e38bd413) INFO  lib389.replica:replica.py:2482 Retry: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is NOT working (expect ee84f135-998e-4b6e-aec9-c5a0438a486c / got description=495133d3-6e2b-421c-a4fa-e6f7e38bd413) INFO  lib389.replica:replica.py:2480 SUCCESS: Replication from ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39004 to ldap://ci-vm-10-0-137-249.hosted.upshift.rdu2.redhat.com:39002 is working INFO  lib389:agreement.py:1095 Agreement (cn=004,cn=replica,cn=dc\3Dexample\2Cdc\3Dcom,cn=mapping tree,cn=config) was successfully removed INFO  lib389:agreement.py:1095 Agreement (cn=004,cn=replica,cn=dc\3Dexample\2Cdc\3Dcom,cn=mapping tree,cn=config) was successfully removed INFO  lib389:tasks.py:1397 cleanAllRUV task (task-10072020_233019) completed successfully INFO  lib389.utils:ticket49463_test.py:186 M1: nsds5ReplicaCleanRUV=4:no:1:dc=example,dc=com

topology_st = <lib389.topologies.TopologyMain object at 0x7ff7f18e5fd0>

def test_ticket50232_normal(topology_st):
"""
The fix for ticket 50232


The test sequence is:
- create suffix
- add suffix entry and some child entries
- "normally" done after populating suffix: enable replication
- get RUV and database generation
- export -r
- import
- get RUV and database generation
- assert database generation has not changed
"""

log.info('Testing Ticket 50232 - export creates not imprtable ldif file, normal creation order')

topology_st.standalone.backend.create(NORMAL_SUFFIX, {BACKEND_NAME: NORMAL_BACKEND_NAME})
topology_st.standalone.mappingtree.create(NORMAL_SUFFIX, bename=NORMAL_BACKEND_NAME, parent=None)

_populate_suffix(topology_st.standalone, NORMAL_BACKEND_NAME)

repl = ReplicationManager(DEFAULT_SUFFIX)
> repl._ensure_changelog(topology_st.standalone)

/export/tests/tickets/ticket50232_test.py:113:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
/usr/local/lib/python3.8/site-packages/lib389/replica.py:1912: in _ensure_changelog
cl.create(properties={
/usr/local/lib/python3.8/site-packages/lib389/_mapped_object.py:971: in create
return self._create(rdn, properties, basedn, ensure=False)
/usr/local/lib/python3.8/site-packages/lib389/_mapped_object.py:946: in _create
self._instance.add_ext_s(e, serverctrls=self._server_controls, clientctrls=self._client_controls, escapehatch='i am sure')
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:176: in inner
return f(ent.dn, ent.toTupleList(), *args[2:])
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:425: in add_ext_s
resp_type, resp_data, resp_msgid, resp_ctrls = self.result3(msgid,all=1,timeout=self.timeout)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:764: in result3
resp_type, resp_data, resp_msgid, decoded_resp_ctrls, retoid, retval = self.result4(
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:774: in result4
ldap_result = self._ldap_call(self._l.result4,msgid,all,timeout,add_ctrls,add_intermediates,add_extop)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:340: in _ldap_call
reraise(exc_type, exc_value, exc_traceback)
/usr/local/lib64/python3.8/site-packages/ldap/compat.py:46: in reraise
raise exc_value
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

self = <lib389.DirSrv object at 0x7ff7f18e5880>
func = <built-in method result4 of LDAP object at 0x7ff7f1de6360>
args = (13, 1, -1, 0, 0, 0), kwargs = {}, diagnostic_message_success = None
exc_type = None, exc_value = None, exc_traceback = None

def _ldap_call(self,func,*args,**kwargs):
"""
Wrapper method mainly for serializing calls into OpenLDAP libs
and trace logs
"""
self._ldap_object_lock.acquire()
if __debug__:
if self._trace_level>=1:
self._trace_file.write('*** %s %s - %s\n%s\n' % (
repr(self),
self._uri,
'.'.join((self.__class__.__name__,func.__name__)),
pprint.pformat((args,kwargs))
))
if self._trace_level>=9:
traceback.print_stack(limit=self._trace_stack_limit,file=self._trace_file)
diagnostic_message_success = None
try:
try:
> result = func(*args,**kwargs)
E ldap.UNWILLING_TO_PERFORM: {'msgtype': 105, 'msgid': 13, 'result': 53, 'desc': 'Server is unwilling to perform', 'ctrls': [], 'info': 'Changelog configuration is part of the backend configuration'}

/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:324: UNWILLING_TO_PERFORM
-------------------------------Captured log setup-------------------------------
INFO  lib389.topologies:topologies.py:115 Instance with parameters {'ldap-port': 38901, 'ldap-secureport': 63601, 'server-id': 'standalone1', 'suffix': 'dc=example,dc=com'} was created.
-------------------------------Captured log call--------------------------------
INFO  lib389:backend.py:80 List backend with suffix=o=normal INFO  lib389:backend.py:290 Creating a local backend INFO  lib389:backend.py:76 List backend cn=normal,cn=ldbm database,cn=plugins,cn=config INFO  lib389:__init__.py:1772 Found entry dn: cn=normal,cn=ldbm database,cn=plugins,cn=config cn: normal nsslapd-cachememsize: 512000 nsslapd-cachesize: -1 nsslapd-directory: /var/lib/dirsrv/slapd-standalone1/db/normal nsslapd-dncachememsize: 16777216 nsslapd-readonly: off nsslapd-require-index: off nsslapd-require-internalop-index: off nsslapd-suffix: o=normal objectClass: top objectClass: extensibleObject objectClass: nsBackendInstance INFO  lib389:mappingTree.py:154 Entry dn: cn="o=normal",cn=mapping tree,cn=config cn: o=normal nsslapd-backend: normal nsslapd-state: backend objectclass: top objectclass: extensibleObject objectclass: nsMappingTree INFO  lib389:__init__.py:1772 Found entry dn: cn=o\3Dnormal,cn=mapping tree,cn=config cn: o=normal nsslapd-backend: normal nsslapd-state: backend objectClass: top objectClass: extensibleObject objectClass: nsMappingTree

topology_st = <lib389.topologies.TopologyMain object at 0x7ff7f18e5fd0>

def test_ticket50232_reverse(topology_st):
"""
The fix for ticket 50232


The test sequence is:
- create suffix
- enable replication before suffix enztry is added
- add suffix entry and some child entries
- get RUV and database generation
- export -r
- import
- get RUV and database generation
- assert database generation has not changed
"""

log.info('Testing Ticket 50232 - export creates not imprtable ldif file, normal creation order')

#
# Setup Replication
#
log.info('Setting up replication...')
repl = ReplicationManager(DEFAULT_SUFFIX)
# repl.create_first_master(topology_st.standalone)
#
# enable dynamic plugins, memberof and retro cl plugin
#
topology_st.standalone.backend.create(REVERSE_SUFFIX, {BACKEND_NAME: REVERSE_BACKEND_NAME})
topology_st.standalone.mappingtree.create(REVERSE_SUFFIX, bename=REVERSE_BACKEND_NAME, parent=None)

> _enable_replica(topology_st.standalone, REVERSE_SUFFIX)

/export/tests/tickets/ticket50232_test.py:155:
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
/export/tests/tickets/ticket50232_test.py:35: in _enable_replica
repl._ensure_changelog(instance)
/usr/local/lib/python3.8/site-packages/lib389/replica.py:1912: in _ensure_changelog
cl.create(properties={
/usr/local/lib/python3.8/site-packages/lib389/_mapped_object.py:971: in create
return self._create(rdn, properties, basedn, ensure=False)
/usr/local/lib/python3.8/site-packages/lib389/_mapped_object.py:946: in _create
self._instance.add_ext_s(e, serverctrls=self._server_controls, clientctrls=self._client_controls, escapehatch='i am sure')
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:176: in inner
return f(ent.dn, ent.toTupleList(), *args[2:])
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:425: in add_ext_s
resp_type, resp_data, resp_msgid, resp_ctrls = self.result3(msgid,all=1,timeout=self.timeout)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:764: in result3
resp_type, resp_data, resp_msgid, decoded_resp_ctrls, retoid, retval = self.result4(
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:774: in result4
ldap_result = self._ldap_call(self._l.result4,msgid,all,timeout,add_ctrls,add_intermediates,add_extop)
/usr/local/lib/python3.8/site-packages/lib389/__init__.py:180: in inner
return f(*args, **kwargs)
/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:340: in _ldap_call
reraise(exc_type, exc_value, exc_traceback)
/usr/local/lib64/python3.8/site-packages/ldap/compat.py:46: in reraise
raise exc_value
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _

self = <lib389.DirSrv object at 0x7ff7f18e5880>
func = <built-in method result4 of LDAP object at 0x7ff7f1de6360>
args = (22, 1, -1, 0, 0, 0), kwargs = {}, diagnostic_message_success = None
exc_type = None, exc_value = None, exc_traceback = None

def _ldap_call(self,func,*args,**kwargs):
"""
Wrapper method mainly for serializing calls into OpenLDAP libs
and trace logs
"""
self._ldap_object_lock.acquire()
if __debug__:
if self._trace_level>=1:
self._trace_file.write('*** %s %s - %s\n%s\n' % (
repr(self),
self._uri,
'.'.join((self.__class__.__name__,func.__name__)),
pprint.pformat((args,kwargs))
))
if self._trace_level>=9:
traceback.print_stack(limit=self._trace_stack_limit,file=self._trace_file)
diagnostic_message_success = None
try:
try:
> result = func(*args,**kwargs)
E ldap.UNWILLING_TO_PERFORM: {'msgtype': 105, 'msgid': 22, 'result': 53, 'desc': 'Server is unwilling to perform', 'ctrls': [], 'info': 'Changelog configuration is part of the backend configuration'}

/usr/local/lib64/python3.8/site-packages/ldap/ldapobject.py:324: UNWILLING_TO_PERFORM
-------------------------------Captured log call--------------------------------
INFO  lib389:backend.py:80 List backend with suffix=o=reverse INFO  lib389:backend.py:290 Creating a local backend INFO  lib389:backend.py:76 List backend cn=reverse,cn=ldbm database,cn=plugins,cn=config INFO  lib389:__init__.py:1772 Found entry dn: cn=reverse,cn=ldbm database,cn=plugins,cn=config cn: reverse nsslapd-cachememsize: 512000 nsslapd-cachesize: -1 nsslapd-directory: /var/lib/dirsrv/slapd-standalone1/db/reverse nsslapd-dncachememsize: 16777216 nsslapd-readonly: off nsslapd-require-index: off nsslapd-require-internalop-index: off nsslapd-suffix: o=reverse objectClass: top objectClass: extensibleObject objectClass: nsBackendInstance INFO  lib389:mappingTree.py:154 Entry dn: cn="o=reverse",cn=mapping tree,cn=config cn: o=reverse nsslapd-backend: reverse nsslapd-state: backend objectclass: top objectclass: extensibleObject objectclass: nsMappingTree INFO  lib389:__init__.py:1772 Found entry dn: cn=o\3Dreverse,cn=mapping tree,cn=config cn: o=reverse nsslapd-backend: reverse nsslapd-state: backend objectClass: top objectClass: extensibleObject objectClass: nsMappingTree