https://bugs.openldap.org/show_bug.cgi?id=10026
--- Comment #11 from Howard Chu hyc@openldap.org --- running verbose:
(venv) viola:~/OD/o10026/tests/test050> ps ax | grep slapd 1611961 pts/4 S+ 0:00 grep slapd (venv) viola:~/OD/o10026/tests/test050> python3 tester.py -v DEBUG:__main__:Starting with /tmp/tmprxrh_foa DEBUG:__main__:Starting on localhost:1389 INFO:searchA:Search A started DEBUG:searchA:message=SyncInfoRefreshDelete(cookie=None, refreshDone=True) DEBUG:__main__:searchA is transitioning to stage=1 DEBUG:searchA:Will be waiting for cookie=rid=000,sid=003,csn=20260613163947.000000Z#000000#001#000000 INFO:pldap.server.server:connid=1 Accepted a new connection DEBUG:pldap.server.server:connid=1 msgid=1 sending BindResponse(msgid=1, controls=None, resultCode=0, matchedDN='', diagnosticMessage='', referral=None, serverSaslCreds=None) INFO:pldap.server.server:connid=2 Accepted a new connection DEBUG:pldap.server.server:connid=2 msgid=1 sending BindResponse(msgid=1, controls=None, resultCode=0, matchedDN='', diagnosticMessage='', referral=None, serverSaslCreds=None) DEBUG:SearchRequest(connid=1, msgid=2):Synccontrol cookie b'rid=001,sid=002' INFO:SearchRequest(connid=1, msgid=2):Have an initial sid=2 search with no cookie DEBUG:SearchRequest(connid=1, msgid=2):target_cookie=rid=000,sid=001,csn=20260613163947.000000Z#000000#001#000000 DEBUG:pldap.server.server:connid=1 msgid=2 sending SearchResultEntry(msgid=2, controls=[SyncStateControl(state=1, cookie=None)], dn='dc=example,dc=com') DEBUG:pldap.server.server:connid=1 msgid=2 sending SyncRefreshDelete(msgid=2, controls=[], cookie=b'rid=000,sid=001,csn=20260613163947.000000Z#000000#001#000000', refreshDone=True) DEBUG:__main__:sid=002 is transitioning to stage=2 INFO:__main__:searchA Reached stage 2 DEBUG:SearchRequest(connid=2, msgid=2):Synccontrol cookie b'rid=001,sid=003' INFO:SearchRequest(connid=2, msgid=2):Closing connection for sid=3 while they have no data DEBUG:pldap.server.server:connid=2 msgid=2 sending ExtendedResponse(msgid=0, controls=None, resultCode=0, matchedDN='', diagnosticMessage='', referral=None, name='1.3.6.1.4.1.1466.20036', value=None) DEBUG:pldap.server.server:connid=2 msgid=3 UnbindRequest processing finished INFO:pldap.server.server:connid=2 Connection closed DEBUG:searchA:message=SearchResultEntry(b'dc=example,dc=com', {b'objectClass': [b'top', b'organization', b'domainRelatedObject', b'dcObject'], b'dc': [b'example'], b'l': [b'Anytown, Michigan'], b'st': [b'Michigan'], b'o': [b'Example, Inc.', b'EX', b'Ex.'], b'description': [b'The Example, Inc. at Anytown'], b'postalAddress': [b'Example, Inc. $ 535 W. William St. $ Anytown, MI 48109 $ US'], b'telephoneNumber': [b'+1 313 555 1817'], b'associatedDomain': [b'example.com']}, [SyncStateControl(state=add, entryUUID='ac5ee4db-450d-409b-b6f7-a6aed1987196', cookie=b'rid=000,sid=003')], encoding='utf-8') INFO:pldap.server.server:connid=3 Accepted a new connection DEBUG:pldap.server.server:connid=3 msgid=1 sending BindResponse(msgid=1, controls=None, resultCode=0, matchedDN='', diagnosticMessage='', referral=None, serverSaslCreds=None) DEBUG:SearchRequest(connid=3, msgid=2):Synccontrol cookie b'rid=001,sid=003' INFO:SearchRequest(connid=3, msgid=2):Closing connection for sid=3 while they have no data DEBUG:pldap.server.server:connid=3 msgid=2 sending ExtendedResponse(msgid=0, controls=None, resultCode=0, matchedDN='', diagnosticMessage='', referral=None, name='1.3.6.1.4.1.1466.20036', value=None) DEBUG:pldap.server.server:connid=3 msgid=3 UnbindRequest processing finished INFO:pldap.server.server:connid=3 Connection closed INFO:pldap.server.server:connid=4 Accepted a new connection DEBUG:pldap.server.server:connid=4 msgid=1 sending BindResponse(msgid=1, controls=None, resultCode=0, matchedDN='', diagnosticMessage='', referral=None, serverSaslCreds=None) DEBUG:SearchRequest(connid=4, msgid=2):Synccontrol cookie b'rid=001,sid=003' INFO:SearchRequest(connid=4, msgid=2):Closing connection for sid=3 while they have no data DEBUG:pldap.server.server:connid=4 msgid=2 sending ExtendedResponse(msgid=0, controls=None, resultCode=0, matchedDN='', diagnosticMessage='', referral=None, name='1.3.6.1.4.1.1466.20036', value=None) DEBUG:pldap.server.server:connid=4 msgid=3 UnbindRequest processing finished INFO:pldap.server.server:connid=4 Connection closed INFO:pldap.server.server:connid=5 Accepted a new connection DEBUG:pldap.server.server:connid=5 msgid=1 sending BindResponse(msgid=1, controls=None, resultCode=0, matchedDN='', diagnosticMessage='', referral=None, serverSaslCreds=None) DEBUG:SearchRequest(connid=5, msgid=2):Synccontrol cookie b'rid=001,sid=003' INFO:SearchRequest(connid=5, msgid=2):Closing connection for sid=3 while they have no data DEBUG:pldap.server.server:connid=5 msgid=2 sending ExtendedResponse(msgid=0, controls=None, resultCode=0, matchedDN='', diagnosticMessage='', referral=None, name='1.3.6.1.4.1.1466.20036', value=None) DEBUG:pldap.server.server:connid=5 msgid=3 UnbindRequest processing finished INFO:pldap.server.server:connid=5 Connection closed INFO:pldap.server.server:connid=6 Accepted a new connection DEBUG:pldap.server.server:connid=6 msgid=1 sending BindResponse(msgid=1, controls=None, resultCode=0, matchedDN='', diagnosticMessage='', referral=None, serverSaslCreds=None) DEBUG:SearchRequest(connid=6, msgid=2):Synccontrol cookie b'rid=001,sid=003' INFO:SearchRequest(connid=6, msgid=2):Closing connection for sid=3 while they have no data DEBUG:pldap.server.server:connid=6 msgid=2 sending ExtendedResponse(msgid=0, controls=None, resultCode=0, matchedDN='', diagnosticMessage='', referral=None, name='1.3.6.1.4.1.1466.20036', value=None) DEBUG:pldap.server.server:connid=6 msgid=3 UnbindRequest processing finished INFO:pldap.server.server:connid=6 Connection closed INFO:pldap.server.server:connid=7 Accepted a new connection DEBUG:pldap.server.server:connid=7 msgid=1 sending BindResponse(msgid=1, controls=None, resultCode=0, matchedDN='', diagnosticMessage='', referral=None, serverSaslCreds=None) DEBUG:SearchRequest(connid=7, msgid=2):Synccontrol cookie b'rid=001,sid=003' INFO:SearchRequest(connid=7, msgid=2):Closing connection for sid=3 while they have no data DEBUG:pldap.server.server:connid=7 msgid=2 sending ExtendedResponse(msgid=0, controls=None, resultCode=0, matchedDN='', diagnosticMessage='', referral=None, name='1.3.6.1.4.1.1466.20036', value=None) DEBUG:pldap.server.server:connid=7 msgid=3 UnbindRequest processing finished INFO:pldap.server.server:connid=7 Connection closed INFO:pldap.server.server:connid=8 Accepted a new connection DEBUG:pldap.server.server:connid=8 msgid=1 sending BindResponse(msgid=1, controls=None, resultCode=0, matchedDN='', diagnosticMessage='', referral=None, serverSaslCreds=None) DEBUG:SearchRequest(connid=8, msgid=2):Synccontrol cookie b'rid=001,sid=003' INFO:SearchRequest(connid=8, msgid=2):Closing connection for sid=3 while they have no data DEBUG:pldap.server.server:connid=8 msgid=2 sending ExtendedResponse(msgid=0, controls=None, resultCode=0, matchedDN='', diagnosticMessage='', referral=None, name='1.3.6.1.4.1.1466.20036', value=None) DEBUG:pldap.server.server:connid=8 msgid=3 UnbindRequest processing finished INFO:pldap.server.server:connid=8 Connection closed INFO:pldap.server.server:connid=9 Accepted a new connection DEBUG:pldap.server.server:connid=9 msgid=1 sending BindResponse(msgid=1, controls=None, resultCode=0, matchedDN='', diagnosticMessage='', referral=None, serverSaslCreds=None) DEBUG:SearchRequest(connid=9, msgid=2):Synccontrol cookie b'rid=001,sid=003' INFO:SearchRequest(connid=9, msgid=2):Closing connection for sid=3 while they have no data DEBUG:pldap.server.server:connid=9 msgid=2 sending ExtendedResponse(msgid=0, controls=None, resultCode=0, matchedDN='', diagnosticMessage='', referral=None, name='1.3.6.1.4.1.1466.20036', value=None) DEBUG:pldap.server.server:connid=9 msgid=3 UnbindRequest processing finished INFO:pldap.server.server:connid=9 Connection closed ^CTraceback (most recent call last): File "/usr/lib/python3.12/asyncio/runners.py", line 118, in run return self._loop.run_until_complete(task) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3.12/asyncio/base_events.py", line 687, in run_until_complete return future.result() ^^^^^^^^^^^^^^^ File "/home/hyc/OD/o10026/tests/test050/tester.py", line 621, in main searchA, searchB, searchC = await search_task ^^^^^^^^^^^^^^^^^ File "/home/hyc/OD/o10026/tests/test050/tester.py", line 390, in persistent_search async for messages in persistent_search: File "/home/hyc/OD/o10026/tests/test050/venv/lib/python3.12/site-packages/syncmonitor/ldap_wrapper.py", line 67, in __aiter__ done, _ = await asyncio.wait({self, next_item}, ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3.12/asyncio/tasks.py", line 464, in wait return await _wait(fs, timeout, return_when, loop) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3.12/asyncio/tasks.py", line 550, in _wait await waiter asyncio.exceptions.CancelledError
During handling of the above exception, another exception occurred:
Traceback (most recent call last): File "/home/hyc/OD/o10026/tests/test050/tester.py", line 649, in <module> asyncio.run(main()) File "/usr/lib/python3.12/asyncio/runners.py", line 194, in run return runner.run(main) ^^^^^^^^^^^^^^^^ File "/usr/lib/python3.12/asyncio/runners.py", line 123, in run raise KeyboardInterrupt() KeyboardInterrupt
(venv) viola:~/OD/o10026/tests/test050>