[Issue 10614] New: On old PowerPC Mac with macOS High Sierra, Version 10.13.6 test suite fails in test050-syncrepl-multiprovider
https://bugs.openldap.org/show_bug.cgi?id=10614
Issue ID: 10614 Summary: On old PowerPC Mac with macOS High Sierra, Version 10.13.6 test suite fails in test050-syncrepl-multiprovider Product: OpenLDAP Version: 2.6.14 Hardware: x86_64 OS: Mac OS Status: UNCONFIRMED Keywords: needs_review Severity: normal Priority: --- Component: test suite Assignee: bugs@openldap.org Reporter: Peter_Dyballa@Web.DE Target Milestone: ---
This is the actual report:
00:10:56 Failed test050-syncrepl-multiprovider for mdb after 115 seconds
How to debug?
https://bugs.openldap.org/show_bug.cgi?id=10614
Peter Dyballa Peter_Dyballa@Web.DE changed:
What |Removed |Added ---------------------------------------------------------------------------- Summary|On old PowerPC Mac with |On old x86_64 Mac with |macOS High Sierra, Version |macOS High Sierra, Version |10.13.6 test suite fails in |10.13.6 test suite fails in |test050-syncrepl-multiprovi |test050-syncrepl-multiprovi |der |der
https://bugs.openldap.org/show_bug.cgi?id=10614
Peter Dyballa Peter_Dyballa@Web.DE changed:
What |Removed |Added ---------------------------------------------------------------------------- Summary|On old x86_64 Mac with |On old x86_64 Mac with |macOS High Sierra, Version |macOS High Sierra, Version |10.13.6 test suite fails in |10.13.6, test suite fails |test050-syncrepl-multiprovi |in |der |test050-syncrepl-multiprovi | |der
https://bugs.openldap.org/show_bug.cgi?id=10614
--- Comment #1 from Peter Dyballa Peter_Dyballa@Web.DE --- On that Mac with macOS High Sierra, Version 10.13.6 or Darwin 17.7.0, the same failure happens with OpenLDAP 2.6.15:
Waiting 7 seconds for servers to resync... Using ldapsearch to read all the entries from server 1... Using ldapsearch to read all the entries from server 2... Using ldapsearch to read all the entries from server 3... Using ldapsearch to read all the entries from server 4... Comparing retrieved entries from server 1 and server 2... test failed - server 1 and server 2 databases differ
00:10:24 Failed test050-syncrepl-multiprovider for mdb after 111 seconds
(exit 1) make[2]: *** [mdb-yes] Error 1 make[1]: *** [test] Error 2 make: *** [test] Error 2 Command failed: cd "/opt/local/var/macports/build/openldap-be6d492f/work/openldap-2.6.15" && /usr/bin/make check
https://bugs.openldap.org/show_bug.cgi?id=10614
--- Comment #2 from Peter Dyballa Peter_Dyballa@Web.DE --- The whole sequence leading to the failure is:
Test succeeded 00:10:12 Finished test049-sync-config for mdb after 30 seconds.
00:10:12 Starting test050-syncrepl-multiprovider for mdb...
running defines.sh Initializing server configurations... Starting server 1 on TCP/IP port 9011... Using ldapsearch to check that server 1 is running... Inserting syncprov overlay on server 1... Starting server 2 on TCP/IP port 9012... Using ldapsearch to check that server 2 is running... Configuring syncrepl on server 2... Starting server 3 on TCP/IP port 9013... Using ldapsearch to check that server 3 is running... Configuring syncrepl on server 3... Starting server 4 on TCP/IP port 9014... Using ldapsearch to check that server 4 is running... Configuring syncrepl on server 4... Adding schema and databases on server 1... Using ldapadd to populate server 1... Waiting 15 seconds for syncrepl to receive changes... Using ldapsearch to read config from server 1... Using ldapsearch to read config from server 2... Using ldapsearch to read config from server 3... Using ldapsearch to read config from server 4... Comparing retrieved configs from server 1 and server 2... Comparing retrieved configs from server 1 and server 3... Comparing retrieved configs from server 1 and server 4... Using ldapsearch to read all the entries from server 1... Using ldapsearch to read all the entries from server 2... Using ldapsearch to read all the entries from server 3... Using ldapsearch to read all the entries from server 4... Comparing retrieved entries from server 1 and server 2... Comparing retrieved entries from server 1 and server 3... Comparing retrieved entries from server 1 and server 4... Using ldapadd to populate server 2... Waiting 7 seconds for syncrepl to receive changes... Using ldapsearch to read all the entries from server 1... Using ldapsearch to read all the entries from server 2... Using ldapsearch to read all the entries from server 3... Using ldapsearch to read all the entries from server 4... Comparing retrieved entries from server 1 and server 2... Comparing retrieved entries from server 1 and server 3... Comparing retrieved entries from server 1 and server 4... Using ldapadd to populate server 3... Waiting 7 seconds for syncrepl to receive changes... Using ldapsearch to read all the entries from server 1... Using ldapsearch to read all the entries from server 2... Using ldapsearch to read all the entries from server 3... Using ldapsearch to read all the entries from server 4... Comparing retrieved entries from server 1 and server 2... Comparing retrieved entries from server 1 and server 3... Comparing retrieved entries from server 1 and server 4... Using ldapmodify to add to server 1 entries that will be deleted... Waiting 7 seconds for syncrepl to receive changes... Using ldapsearch to read all the entries from server 1... Using ldapsearch to read all the entries from server 2... Using ldapsearch to read all the entries from server 3... Using ldapsearch to read all the entries from server 4... Comparing retrieved entries from server 1 and server 2... Comparing retrieved entries from server 1 and server 3... Comparing retrieved entries from server 1 and server 4... Using ldapmodify to delete entries from server 2... Waiting 7 seconds for syncrepl to receive changes... Using ldapmodify to delete entries from server 3... Waiting 7 seconds for syncrepl to receive changes... Using ldapsearch to read all the entries from server 1... Using ldapsearch to read all the entries from server 2... Using ldapsearch to read all the entries from server 3... Using ldapsearch to read all the entries from server 4... Comparing retrieved entries from server 1 and server 2... Comparing retrieved entries from server 1 and server 3... Comparing retrieved entries from server 1 and server 4... Restarting servers... Starting server 1 on TCP/IP port 9011... Using ldapsearch to check that server 1 is running... Starting server 2 on TCP/IP port 9012... Starting server 3 on TCP/IP port 9013... Starting server 4 on TCP/IP port 9014... Using ldapsearch to check that server 2 is running... Using ldapsearch to check that server 3 is running... Using ldapsearch to check that server 4 is running... Waiting 7 seconds for servers to resync... Using ldapmodify to add/modify/delete entries from server 1... iteration 1 iteration 2 iteration 3 iteration 4 iteration 5 iteration 6 iteration 7 iteration 8 iteration 9 iteration 10 Waiting 7 seconds for servers to resync... Using ldapsearch to read all the entries from server 1... Using ldapsearch to read all the entries from server 2... Using ldapsearch to read all the entries from server 3... Using ldapsearch to read all the entries from server 4... Comparing retrieved entries from server 1 and server 2... Comparing retrieved entries from server 1 and server 3... Comparing retrieved entries from server 1 and server 4... test failed - server 1 and server 4 databases differ
00:12:02 Failed test050-syncrepl-multiprovider for mdb after 110 seconds
(exit 1) make[2]: *** [mdb-yes] Error 1 make[1]: *** [test] Error 2 make: *** [test] Error 2
https://bugs.openldap.org/show_bug.cgi?id=10614
--- Comment #3 from Peter Dyballa Peter_Dyballa@Web.DE --- The directory openldap-2.6.15/tests/testrun has:
-rw------- 1 root macports 8 2026-10-06 11:13 configpw -rw------- 1 root macports 20526 2026-10-06 11:14 server1.flt -rw------- 1 root macports 20526 2026-10-06 11:14 server1.out -rw------- 1 root macports 20526 2026-10-06 11:14 server2.flt -rw------- 1 root macports 20526 2026-10-06 11:14 server2.out -rw------- 1 root macports 20526 2026-10-06 11:14 server3.flt -rw------- 1 root macports 20526 2026-10-06 11:14 server3.out -rw------- 1 root macports 20612 2026-10-06 11:14 server4.flt -rw------- 1 root macports 20612 2026-10-06 11:14 server4.out -rw------- 1 root macports 1450992 2026-10-06 11:14 slapd.1.log -rw------- 1 root macports 2055667 2026-10-06 11:14 slapd.2.log -rw------- 1 root macports 1976087 2026-10-06 11:14 slapd.3.log -rw------- 1 root macports 1935516 2026-10-06 11:14 slapd.4.log drwx------ 4 root macports 136 2026-10-06 11:13 srv1 drwx------ 4 root macports 136 2026-10-06 11:13 srv2 drwx------ 4 root macports 136 2026-10-06 11:13 srv3 drwx------ 4 root macports 136 2026-10-06 11:13 srv4 -rw------- 1 root macports 4272 2026-10-06 11:14 test.out -rw------- 1 root macports 1238 2026-10-06 11:13 tmp
with:
root 291 /\ wc -l slapd.?.log 19304 slapd.1.log 28153 slapd.2.log 26931 slapd.3.log 26411 slapd.4.log 100799 total
https://bugs.openldap.org/show_bug.cgi?id=10614
--- Comment #4 from Peter Dyballa Peter_Dyballa@Web.DE --- slapd.1.log has:
19205 6ac4bc0b.334584d0 0x70000b951000 => send_search_entry: conn 1017 dn="cn=Barbara Jensen,ou=Information Technology Division,ou=People,dc=example,dc=com" 19206 6ac4bc0b.3345dea8 0x70000b951000 ber_flush2: 750 bytes to sd 30 19207 6ac4bc0b.33464ff0 0x70000b951000 <= send_search_entry: conn 1017 exit. 19208 6ac4bc0b.334686a0 0x70000b951000 => mdb_entry_decode: 19209 6ac4bc0b.3346adb0 0x70000b951000 <= mdb_entry_decode 19210 6ac4bc0b.3346e078 0x70000b951000 => send_search_entry: conn 1017 dn="cn=James A Jones 2,ou=Information Technology Division,ou=People,dc=example,dc=com" 19211 6ac4bc0b.33472e98 0x70000b951000 ber_flush2: 676 bytes to sd 30 19212 6ac4bc0b.3347a3c8 0x70000b951000 <= send_search_entry: conn 1017 exit. 19213 6ac4bc0b.3347e630 0x70000b951000 send_ldap_result: conn=1017 op=1 p=3 19214 6ac4bc0b.33480d40 0x70000b951000 send_ldap_result: err=0 matched="" text="" 19215 6ac4bc0b.33483450 0x70000b951000 send_ldap_response: msgid=2 tag=101 err=0 19216 6ac4bc0b.33486330 0x70000b951000 ber_flush2: 14 bytes to sd 30 19217 6ac4bc0b.3348f7a0 0x70000b951000 conn=1017 op=1 SEARCH RESULT tag=101 err=0 qtime=0.000049 etime=0.160405 nentries=21 text= 19218 6ac4bc0b.335fdee8 0x70000c154000 connection_get(30) 19219 6ac4bc0b.33604860 0x70000c154000 connection_get(30): got connid=1017 19220 6ac4bc0b.33607740 0x70000c154000 connection_read(30): checking for input on id=1017 19221 6ac4bc0b.33609a68 0x70000c154000 ber_get_next 19222 6ac4bc0b.3360e888 0x70000c154000 ber_get_next: tag 0x30 len 5 contents: 19223 6ac4bc0b.33611768 0x70000c154000 op tag 0x42, time 1791278091 19224 6ac4bc0b.33614648 0x70000c154000 ber_get_next 19225 6ac4bc0b.33617cf8 0x70000c154000 ber_get_next on fd 30 failed errno=0 (Undefined error: 0) 19226 6ac4bc0b.3361ea58 0x70000c154000 conn=1017 op=2 do_unbind 19227 6ac4bc0b.33621550 0x70000c154000 conn=1017 op=2 UNBIND 19228 6ac4bc0b.33624818 0x70000c154000 connection_close: conn=1017 sd=30 19229 6ac4bc0b.33634dd0 0x70000c154000 conn=1017 fd=30 closed 19230 6ac4bc0d.061a0eb8 0x70000893f000 daemon: shutdown requested and initiated. 19231 6ac4bc0d.061c3198 0x70000893f000 connection_close: conn=1001 sd=18 19232 6ac4bc0d.061d5690 0x70000893f000 conn=1001 fd=18 closed (slapd shutdown) 19233 6ac4bc0d.061dac80 0x70000893f000 connection_close: conn=1002 sd=19 19234 6ac4bc0d.061e6fd0 0x70000893f000 conn=1002 fd=19 closed (slapd shutdown) 19235 6ac4bc0d.061ecd90 0x70000893f000 connection_close: conn=1003 sd=20 19236 6ac4bc0d.061f90e0 0x70000893f000 conn=1003 fd=20 closed (slapd shutdown) 19237 6ac4bc0d.061fdf00 0x70000893f000 connection_close: conn=1004 sd=21 19238 6ac4bc0d.06209a80 0x70000893f000 conn=1004 fd=21 closed (slapd shutdown) 19239 6ac4bc0d.0620ec88 0x70000893f000 connection_close: conn=1005 sd=22 19240 6ac4bc0d.0621a420 0x70000893f000 conn=1005 fd=22 closed (slapd shutdown) 19241 6ac4bc0d.0621f628 0x70000893f000 connection_close: conn=1006 sd=23 19242 6ac4bc0d.06229a38 0x70000893f000 conn=1006 fd=23 closed (slapd shutdown) 19243 6ac4bc0d.0626b500 0x70000893f000 slapd shutdown: waiting for 0 operations/tasks to finish 19244 6ac4bc0d.06311d10 0x7fff8b66a380 slapd shutdown: initiated 19245 6ac4bc0d.063157a8 0x7fff8b66a380 conn=-1 op=0 syncprov_checkpoint: running checkpoint 19246 6ac4bc0d.063176e8 0x7fff8b66a380 mdb_modify: dc=example,dc=com 19247 6ac4bc0d.0631a1e0 0x7fff8b66a380 mdb_dn2entry("dc=example,dc=com") 19248 6ac4bc0d.0631b568 0x7fff8b66a380 => mdb_dn2id("dc=example,dc=com") 19249 6ac4bc0d.0631d890 0x7fff8b66a380 <= mdb_dn2id: got id=0x1 19250 6ac4bc0d.0631fbb8 0x7fff8b66a380 => mdb_entry_decode: 19251 6ac4bc0d.06325590 0x7fff8b66a380 <= mdb_entry_decode 19252 6ac4bc0d.06329028 0x7fff8b66a380 mdb_modify_internal: 0x00000001: dc=example,dc=com 19253 6ac4bc0d.0632cea8 0x7fff8b66a380 mdb_modify_internal: replace contextCSN 19254 6ac4bc0d.06330170 0x7fff8b66a380 => mdb_entry_encode(0x00000001): dc=example,dc=com 19255 6ac4bc0d.06331cc8 0x7fff8b66a380 <= mdb_entry_encode(0x00000001): dc=example,dc=com 19256 6ac4bc0d.215c4498 0x7fff8b66a380 mdb_modify: updated id=00000001 dn="dc=example,dc=com" 19257 6ac4bc0d.215d6d78 0x7fff8b66a380 send_ldap_result: conn=-1 op=0 p=0 19258 6ac4bc0d.215da810 0x7fff8b66a380 send_ldap_result: err=0 matched="" text="" 19259 6ac4bc0d.29827ae8 0x7fff8b66a380 slapd destroy: freeing system resources. 19260 6ac4bc0d.2983f1e8 0x7fff8b66a380 syncinfo_free: rid=002 19261 6ac4bc0d.29843c20 0x7fff8b66a380 connection_get(25) 19262 6ac4bc0d.298472d0 0x7fff8b66a380 connection_get(25): got connid=0 19263 6ac4bc0d.2984e800 0x7fff8b66a380 ldap_do_free_request: asked to free lr 0x7feae9801370 msgid 2 refcnt 0 19264 6ac4bc0d.29853620 0x7fff8b66a380 ldap_free_connection 1 1 19265 6ac4bc0d.29856cd0 0x7fff8b66a380 ldap_send_unbind 19266 6ac4bc0d.2985b320 0x7fff8b66a380 ber_flush2: 7 bytes to sd 25 19267 6ac4bc0d.29870ae0 0x7fff8b66a380 ldap_free_connection: actually freed 19268 6ac4bc0d.2993e7d8 0x7fff8b66a380 connection_get(29) 19269 6ac4bc0d.29941e88 0x7fff8b66a380 connection_get(29): got connid=0 19270 6ac4bc0d.299468c0 0x7fff8b66a380 ldap_do_free_request: asked to free lr 0x7feae7459970 msgid 2 refcnt 0 19271 6ac4bc0d.299497a0 0x7fff8b66a380 ldap_free_connection 1 1 19272 6ac4bc0d.2994beb0 0x7fff8b66a380 ldap_send_unbind 19273 6ac4bc0d.2994ed90 0x7fff8b66a380 ber_flush2: 7 bytes to sd 29 19274 6ac4bc0d.2995bc98 0x7fff8b66a380 ldap_free_connection: actually freed 19275 6ac4bc0d.299eb960 0x7fff8b66a380 connection_get(27) 19276 6ac4bc0d.299eec28 0x7fff8b66a380 connection_get(27): got connid=0 19277 6ac4bc0d.299f3278 0x7fff8b66a380 ldap_do_free_request: asked to free lr 0x7feae7603970 msgid 2 refcnt 0 19278 6ac4bc0d.299f6540 0x7fff8b66a380 ldap_free_connection 1 1 19279 6ac4bc0d.299f8c50 0x7fff8b66a380 ldap_send_unbind 19280 6ac4bc0d.299fbb30 0x7fff8b66a380 ber_flush2: 7 bytes to sd 27 19281 6ac4bc0d.29a07e80 0x7fff8b66a380 ldap_free_connection: actually freed 19282 6ac4bc0d.29af4f78 0x7fff8b66a380 syncinfo_free: rid=012 19283 6ac4bc0d.29af95c8 0x7fff8b66a380 connection_get(26) 19284 6ac4bc0d.29afbcd8 0x7fff8b66a380 connection_get(26): got connid=0 19285 6ac4bc0d.29afff40 0x7fff8b66a380 ldap_do_free_request: asked to free lr 0x7feae771a3d0 msgid 2 refcnt 0 19286 6ac4bc0d.29b03208 0x7fff8b66a380 ldap_free_connection 1 1 19287 6ac4bc0d.29b05530 0x7fff8b66a380 ldap_send_unbind 19288 6ac4bc0d.29b08410 0x7fff8b66a380 ber_flush2: 7 bytes to sd 26 19289 6ac4bc0d.29b14b48 0x7fff8b66a380 ldap_free_connection: actually freed 19290 6ac4bc0d.2d1cacf0 0x7fff8b66a380 connection_get(24) 19291 6ac4bc0d.2d1d1e38 0x7fff8b66a380 connection_get(24): got connid=0 19292 6ac4bc0d.2d1dbe60 0x7fff8b66a380 ldap_do_free_request: asked to free lr 0x7feae77198e0 msgid 2 refcnt 0 19293 6ac4bc0d.2d1e1c20 0x7fff8b66a380 ldap_free_connection 1 1 19294 6ac4bc0d.2d1e4330 0x7fff8b66a380 ldap_send_unbind 19295 6ac4bc0d.2d1e79e0 0x7fff8b66a380 ber_flush2: 7 bytes to sd 24 19296 6ac4bc0d.2d1f7f98 0x7fff8b66a380 ldap_free_connection: actually freed 19297 6ac4bc0d.2d28b310 0x7fff8b66a380 connection_get(28) 19298 6ac4bc0d.2d28e9c0 0x7fff8b66a380 connection_get(28): got connid=0 19299 6ac4bc0d.2d2933f8 0x7fff8b66a380 ldap_do_free_request: asked to free lr 0x7feae7719ea0 msgid 2 refcnt 0 19300 6ac4bc0d.2d2962d8 0x7fff8b66a380 ldap_free_connection 1 1 19301 6ac4bc0d.2d2989e8 0x7fff8b66a380 ldap_send_unbind 19302 6ac4bc0d.2d29bcb0 0x7fff8b66a380 ber_flush2: 7 bytes to sd 28 19303 6ac4bc0d.2d2a8bb8 0x7fff8b66a380 ldap_free_connection: actually freed 19304 6ac4bc0d.2d489b08 0x7fff8b66a380 slapd stopped.
https://bugs.openldap.org/show_bug.cgi?id=10614
--- Comment #5 from Peter Dyballa Peter_Dyballa@Web.DE --- slapd.2.log has:
27979 6ac4bc0d.043679d8 0x70001355e000 syncrepl_entry: rid=013 LDAP_RES_SEARCH_ENTRY(LDAP_SYNC_MODIFY) csn=20261006091443.710168Z#000000#001#000000 tid 0x70001355e000 27980 6ac4bc0d.0436aca0 0x70001355e000 => mdb_search 27981 6ac4bc0d.04371a00 0x70001355e000 mdb_dn2entry("dc=example,dc=com") 27982 6ac4bc0d.043744f8 0x70001355e000 => mdb_dn2id("dc=example,dc=com") 27983 6ac4bc0d.043777c0 0x70001355e000 <= mdb_dn2id: got id=0x1 27984 6ac4bc0d.0437aa88 0x70001355e000 => mdb_entry_decode: 27985 6ac4bc0d.0437d198 0x70001355e000 <= mdb_entry_decode 27986 6ac4bc0d.04380848 0x70001355e000 search_candidates: base="dc=example,dc=com" (0x00000001) scope=2 27987 6ac4bc0d.04383340 0x70001355e000 => mdb_equality_candidates (entryUUID) 27988 6ac4bc0d.04385e38 0x70001355e000 => key_read 27989 6ac4bc0d.04388930 0x70001355e000 mdb_idl_fetch_key: [590d9fc0] 27990 6ac4bc0d.0438b810 0x70001355e000 <= mdb_index_read 1 candidates 27991 6ac4bc0d.0438e308 0x70001355e000 <= mdb_equality_candidates: id=1, first=22, last=22 27992 6ac4bc0d.04393128 0x70001355e000 mdb_search_candidates: id=1 first=22 last=22 27993 6ac4bc0d.043963f0 0x70001355e000 => mdb_entry_decode: 27994 6ac4bc0d.04398b00 0x70001355e000 <= mdb_entry_decode 27995 6ac4bc0d.043a0800 0x70001355e000 send_ldap_result: conn=-1 op=0 p=0 27996 6ac4bc0d.043a5238 0x70001355e000 send_ldap_result: err=0 matched="" text="" 27997 6ac4bc0d.043a88e8 0x70001355e000 syncrepl_entry: rid=013 be_search (0) 27998 6ac4bc0d.043abbb0 0x70001355e000 syncrepl_entry: rid=013 cn=Add-Mod-Del,dc=example,dc=com 27999 6ac4bc0d.043ae6a8 0x70001355e000 slap_queue_csn: queueing 0x7fe82d957940 20261006091443.710168Z#000000#001#000000 28000 6ac4bc0d.043b2140 0x70001355e000 => mdb_entry_get: ndn: "cn=add-mod-del,dc=example,dc=com" 28001 6ac4bc0d.043b4850 0x70001355e000 => mdb_entry_get: oc: "(null)", at: "(null)" 28002 6ac4bc0d.043b6f60 0x70001355e000 mdb_dn2entry("cn=add-mod-del,dc=example,dc=com") 28003 6ac4bc0d.043b9288 0x70001355e000 => mdb_dn2id("cn=add-mod-del,dc=example,dc=com") 28004 6ac4bc0d.043bc938 0x70001355e000 <= mdb_dn2id: got id=0x16 28005 6ac4bc0d.043bf818 0x70001355e000 => mdb_entry_decode: 28006 6ac4bc0d.043c1b40 0x70001355e000 <= mdb_entry_decode 28007 6ac4bc0d.043c4250 0x70001355e000 mdb_entry_get: rc=0 28008 6ac4bc0d.043c6d48 0x70001355e000 conn=-1 op=0 syncprov_matchops: recording uuid for dn=cn=Add-Mod-Del,dc=example,dc=com on opc=0x106fdea08 28009 6ac4bc0d.043c9458 0x70001355e000 conn=1006 op=1 syncprov_matchops: skipping original sid 001 28010 6ac4bc0d.043cc338 0x70001355e000 conn=1002 op=1 syncprov_matchops: sid 004 fscope 1 rc 6 28011 6ac4bc0d.043cea48 0x70001355e000 conn=1000 op=1 syncprov_matchops: skipping relayed sid 003 28012 6ac4bc0d.043d1540 0x70001355e000 mdb_modify: cn=Add-Mod-Del,dc=example,dc=com 28013 6ac4bc0d.043d53c0 0x70001355e000 mdb_dn2entry("cn=add-mod-del,dc=example,dc=com") 28014 6ac4bc0d.043d7ad0 0x70001355e000 => mdb_dn2id("cn=add-mod-del,dc=example,dc=com") 28015 6ac4bc0d.043dad98 0x70001355e000 <= mdb_dn2id: got id=0x16 28016 6ac4bc0d.043dd890 0x70001355e000 => mdb_entry_decode: 28017 6ac4bc0d.043dfbb8 0x70001355e000 <= mdb_entry_decode 28018 6ac4bc0d.043e2e80 0x70001355e000 mdb_modify_internal: 0x00000016: cn=Add-Mod-Del,dc=example,dc=com 28019 6ac4bc0d.043e70e8 0x70001355e000 mdb_modify_internal: replace description 28020 6ac4bc0d.043ea3b0 0x70001355e000 mdb_modify_internal: replace entryCSN 28021 6ac4bc0d.043eda60 0x70001355e000 mdb_modify_internal: replace modifiersName 28022 6ac4bc0d.043f0940 0x70001355e000 mdb_modify_internal: replace modifyTimestamp 28023 6ac4bc0d.043f4ba8 0x70001355e000 mdb_idl_delete_keys: 16 Nä98^C 28024 6ac4bc0d.043f9db0 0x70001355e000 mdb_idl_insert_keys: 16 Nä98^C 28025 6ac4bc0d.043fe7e8 0x70001355e000 => mdb_entry_encode(0x00000016): cn=Add-Mod-Del,dc=example,dc=com 28026 6ac4bc0d.044016c8 0x70001355e000 <= mdb_entry_encode(0x00000016): cn=Add-Mod-Del,dc=example,dc=com 28027 6ac4bc0d.0619f748 0x70000ed43000 daemon: shutdown requested and initiated. 28028 6ac4bc0d.061de330 0x70000ed43000 connection_close: conn=1000 sd=24 28029 6ac4bc0d.061ecd90 0x70000ed43000 conn=1000 fd=24 closed (slapd shutdown) 28030 6ac4bc0d.061f2380 0x70000ed43000 connection_close: conn=1001 sd=25 28031 6ac4bc0d.061feab8 0x70000ed43000 conn=1001 fd=25 closed (slapd shutdown) 28032 6ac4bc0d.062040a8 0x70000ed43000 connection_close: conn=1002 sd=26 28033 6ac4bc0d.0620f840 0x70000ed43000 conn=1002 fd=26 closed (slapd shutdown) 28034 6ac4bc0d.06214e30 0x70000ed43000 connection_close: conn=1003 sd=27 28035 6ac4bc0d.062201e0 0x70000ed43000 conn=1003 fd=27 closed (slapd shutdown) 28036 6ac4bc0d.06225bb8 0x70000ed43000 connection_close: conn=1005 sd=28 28037 6ac4bc0d.06230f68 0x70000ed43000 conn=1005 fd=28 closed (slapd shutdown) 28038 6ac4bc0d.06236170 0x70000ed43000 connection_close: conn=1006 sd=29 28039 6ac4bc0d.06241cf0 0x70000ed43000 conn=1006 fd=29 closed (slapd shutdown) 28040 6ac4bc0d.0624d488 0x70000ed43000 slapd shutdown: waiting for 2 operations/tasks to finish 28041 6ac4bc0d.1727d370 0x70001355e000 mdb_modify: updated id=00000016 dn="cn=Add-Mod-Del,dc=example,dc=com" 28042 6ac4bc0d.1728d158 0x70001355e000 send_ldap_result: conn=-1 op=0 p=0 28043 6ac4bc0d.17290808 0x70001355e000 send_ldap_result: err=0 matched="" text="" 28044 6ac4bc0d.172990c0 0x70001355e000 slap_graduate_commit_csn: removing 0x7fe82d957940 20261006091443.710168Z#000000#001#000000 28045 6ac4bc0d.19405718 0x70001355e000 syncrepl_entry: rid=013 be_modify cn=Add-Mod-Del,dc=example,dc=com (0) 28046 6ac4bc0d.19415118 0x70001355e000 slap_queue_csn: queueing 0x7fe82d95d570 20261006091443.710168Z#000000#001#000000 28047 6ac4bc0d.1941aaf0 0x70001355e000 mdb_modify: dc=example,dc=com 28048 6ac4bc0d.194256d0 0x70001355e000 mdb_dn2entry("dc=example,dc=com") 28049 6ac4bc0d.19429168 0x70001355e000 => mdb_dn2id("dc=example,dc=com") 28050 6ac4bc0d.1942f310 0x70001355e000 <= mdb_dn2id: got id=0x1 28051 6ac4bc0d.19433960 0x70001355e000 => mdb_entry_decode: 28052 6ac4bc0d.19438780 0x70001355e000 <= mdb_entry_decode 28053 6ac4bc0d.1943c600 0x70001355e000 mdb_modify_internal: 0x00000001: dc=example,dc=com 28054 6ac4bc0d.19444ad0 0x70001355e000 mdb_modify_internal: replace contextCSN 28055 6ac4bc0d.1944e328 0x70001355e000 => mdb_entry_encode(0x00000001): dc=example,dc=com 28056 6ac4bc0d.19452d60 0x70001355e000 <= mdb_entry_encode(0x00000001): dc=example,dc=com 28057 6ac4bc0d.2cf94a58 0x70001355e000 mdb_modify: updated id=00000001 dn="dc=example,dc=com" 28058 6ac4bc0d.2cfae480 0x70001355e000 send_ldap_result: conn=-1 op=0 p=0 28059 6ac4bc0d.2cfb3e58 0x70001355e000 send_ldap_result: err=0 matched="" text="" 28060 6ac4bc0d.2cfc1530 0x70001355e000 slap_graduate_commit_csn: removing 0x7fe82d95d570 20261006091443.710168Z#000000#001#000000 28061 6ac4bc0d.2cfd9bd0 0x700011552000 do_syncrep2: rid=011 CSN too old, ignoring 20261006091443.710168Z#000000#001#000000 (cn=Add-Mod-Del,dc=example,dc=com) 28062 6ac4bc0d.2cfe5750 0x70001355e000 ldap_msgfree 28063 6ac4bc0d.2cfeb510 0x70001355e000 ldap_result ld 0x7fe82c702d60 msgid 2 28064 6ac4bc0d.2cfef390 0x70001355e000 wait4msg ld 0x7fe82c702d60 msgid 2 (timeout 0 usec) 28065 6ac4bc0d.2cff2a40 0x70001355e000 wait4msg continue ld 0x7fe82c702d60 msgid 2 all 0 28066 6ac4bc0d.2cfe6ad8 0x700011552000 ldap_msgfree 28067 6ac4bc0d.2cff6ca8 0x70001355e000 ** ld 0x7fe82c702d60 Connections: 28068 6ac4bc0d.2d002828 0x70001355e000 * host: localhost port: 9013 (default) 28069 6ac4bc0d.2d0089d0 0x70001355e000 * from: IP=[::1]:53289 28070 6ac4bc0d.2d00b8b0 0x70001355e000 refcnt: 2 status: Connected 28071 6ac4bc0d.2d013d80 0x70001355e000 last used: Tue Oct 6 11:14:18 2026 28072 28073 6ac4bc0d.2d017c00 0x70001355e000 28074 6ac4bc0d.2d01aec8 0x70001355e000 ** ld 0x7fe82c702d60 Outstanding Requests: 28075 6ac4bc0d.2d01e190 0x70001355e000 * msgid 2, origid 2, status InProgress 28076 6ac4bc0d.2d021070 0x70001355e000 outstanding referrals 0, parent count 0 28077 6ac4bc0d.2d023f50 0x70001355e000 ld 0x7fe82c702d60 request count 1 (abandoned 0) 28078 6ac4bc0d.2d026e30 0x70001355e000 ** ld 0x7fe82c702d60 Response Queue: 28079 6ac4bc0d.2d029928 0x70001355e000 Empty 28080 6ac4bc0d.2d02c808 0x70001355e000 ld 0x7fe82c702d60 response count 0 28081 6ac4bc0d.2d02f6e8 0x70001355e000 ldap_chkResponseList ld 0x7fe82c702d60 msgid 2 all 0 28082 6ac4bc0d.2d0325c8 0x70001355e000 ldap_chkResponseList returns ld 0x7fe82c702d60 NULL 28083 6ac4bc0d.2d035890 0x70001355e000 ldap_int_select 28084 6ac4bc0d.2d03ed00 0x70001355e000 read1msg: ld 0x7fe82c702d60 msgid 2 all 0 28085 6ac4bc0d.2d042b80 0x70001355e000 ber_get_next 28086 6ac4bc0d.2d04d760 0x70001355e000 ber_get_next: tag 0x30 len 158 contents: 28087 6ac4bc0d.2d0519c8 0x70001355e000 ldap_find_request_by_msgid: msgid 2, lr 0x7fe82d805440 lr->lr_refcnt = 1 28088 6ac4bc0d.2d054c90 0x70001355e000 read1msg: ld 0x7fe82c702d60 msgid 2 message type search-entry 28089 6ac4bc0d.2d057f58 0x70001355e000 ldap_return_request: lrx 0x7fe82d805440, lr 0x7fe82d805440 28090 6ac4bc0d.2d05ae38 0x70001355e000 ldap_return_request: lrx->lr_msgid 2, lrx->lr_refcnt is now 0, lr is still present 28091 6ac4bc0d.2d05f0a0 0x70001355e000 ldap_msgfree 28092 6ac4bc0d.2d062b38 0x70001355e000 connection_get(20) 28093 6ac4bc0d.2d065e00 0x70001355e000 connection_get(20): got connid=0 28094 6ac4bc0d.2d070210 0x70001355e000 ldap_do_free_request: asked to free lr 0x7fe82d805440 msgid 2 refcnt 0 28095 6ac4bc0d.2d075030 0x70001355e000 ldap_free_connection 1 1 28096 6ac4bc0d.2d0786e0 0x70001355e000 ldap_send_unbind 28097 6ac4bc0d.2d0803e0 0x70001355e000 ber_flush2: 7 bytes to sd 20 28098 6ac4bc0d.2d099e08 0x70001355e000 ldap_free_connection: actually freed 28099 6ac4bc0d.2d0a4600 0x70001355e000 do_syncrepl: rid=013 rc -100 quitting 28100 6ac4bc0d.2d11df50 0x7fff8b66a380 slapd shutdown: initiated 28101 6ac4bc0d.2d12cd98 0x7fff8b66a380 conn=-1 op=0 syncprov_checkpoint: running checkpoint 28102 6ac4bc0d.2d131000 0x7fff8b66a380 mdb_modify: dc=example,dc=com 28103 6ac4bc0d.2d13b028 0x7fff8b66a380 mdb_dn2entry("dc=example,dc=com") 28104 6ac4bc0d.2d140618 0x7fff8b66a380 => mdb_dn2id("dc=example,dc=com") 28105 6ac4bc0d.2d148700 0x7fff8b66a380 <= mdb_dn2id: got id=0x1 28106 6ac4bc0d.2d14e0d8 0x7fff8b66a380 => mdb_entry_decode: 28107 6ac4bc0d.2d1532e0 0x7fff8b66a380 <= mdb_entry_decode 28108 6ac4bc0d.2d157d18 0x7fff8b66a380 mdb_modify_internal: 0x00000001: dc=example,dc=com 28109 6ac4bc0d.2d163898 0x7fff8b66a380 mdb_modify_internal: replace contextCSN 28110 6ac4bc0d.2d16f800 0x7fff8b66a380 => mdb_entry_encode(0x00000001): dc=example,dc=com 28111 6ac4bc0d.2d173e50 0x7fff8b66a380 <= mdb_entry_encode(0x00000001): dc=example,dc=com 28112 6ac4bc0d.3989c450 0x7fff8b66a380 mdb_modify: updated id=00000001 dn="dc=example,dc=com" 28113 6ac4bc0d.398add90 0x7fff8b66a380 send_ldap_result: conn=-1 op=0 p=0 28114 6ac4bc0d.398b1828 0x7fff8b66a380 send_ldap_result: err=0 matched="" text="" 28115 6ac4bc0e.001d9e08 0x7fff8b66a380 slapd destroy: freeing system resources. 28116 6ac4bc0e.001ec6e8 0x7fff8b66a380 syncinfo_free: rid=002 28117 6ac4bc0e.001f2890 0x7fff8b66a380 connection_get(23) 28118 6ac4bc0e.001f5388 0x7fff8b66a380 connection_get(23): got connid=0 28119 6ac4bc0e.001fb530 0x7fff8b66a380 ldap_do_free_request: asked to free lr 0x7fe82c419ad0 msgid 2 refcnt 0 28120 6ac4bc0e.001fe7f8 0x7fff8b66a380 ldap_free_connection 1 1 28121 6ac4bc0e.002012f0 0x7fff8b66a380 ldap_send_unbind 28122 6ac4bc0e.00205558 0x7fff8b66a380 ber_flush2: 7 bytes to sd 23 28123 6ac4bc0e.0021ad18 0x7fff8b66a380 ldap_free_connection: actually freed 28124 6ac4bc0e.002b9440 0x7fff8b66a380 connection_get(21) 28125 6ac4bc0e.002bd6a8 0x7fff8b66a380 connection_get(21): got connid=0 28126 6ac4bc0e.002c20e0 0x7fff8b66a380 ldap_do_free_request: asked to free lr 0x7fe82c602630 msgid 2 refcnt 0 28127 6ac4bc0e.002c5790 0x7fff8b66a380 ldap_free_connection 1 1 28128 6ac4bc0e.002c7ea0 0x7fff8b66a380 ldap_send_unbind 28129 6ac4bc0e.002cb168 0x7fff8b66a380 ber_flush2: 7 bytes to sd 21 28130 6ac4bc0e.002d8840 0x7fff8b66a380 ldap_free_connection: actually freed 28131 6ac4bc0e.003665c8 0x7fff8b66a380 connection_get(22) 28132 6ac4bc0e.00369890 0x7fff8b66a380 connection_get(22): got connid=0 28133 6ac4bc0e.0036dee0 0x7fff8b66a380 ldap_do_free_request: asked to free lr 0x7fe82c418860 msgid 2 refcnt 0 28134 6ac4bc0e.00370dc0 0x7fff8b66a380 ldap_free_connection 1 1 28135 6ac4bc0e.003734d0 0x7fff8b66a380 ldap_send_unbind 28136 6ac4bc0e.003763b0 0x7fff8b66a380 ber_flush2: 7 bytes to sd 22 28137 6ac4bc0e.00382700 0x7fff8b66a380 ldap_free_connection: actually freed 28138 6ac4bc0e.00474230 0x7fff8b66a380 syncinfo_free: rid=012 28139 6ac4bc0e.004ff0d8 0x7fff8b66a380 connection_get(18) 28140 6ac4bc0e.00502788 0x7fff8b66a380 connection_get(18): got connid=0 28141 6ac4bc0e.00506dd8 0x7fff8b66a380 ldap_do_free_request: asked to free lr 0x7fe82db00ef0 msgid 2 refcnt 0 28142 6ac4bc0e.00509cb8 0x7fff8b66a380 ldap_free_connection 1 1 28143 6ac4bc0e.0050c3c8 0x7fff8b66a380 ldap_send_unbind 28144 6ac4bc0e.0050f2a8 0x7fff8b66a380 ber_flush2: 7 bytes to sd 18 28145 6ac4bc0e.0051c1b0 0x7fff8b66a380 ldap_free_connection: actually freed 28146 6ac4bc0e.00592c20 0x7fff8b66a380 connection_get(19) 28147 6ac4bc0e.00595b00 0x7fff8b66a380 connection_get(19): got connid=0 28148 6ac4bc0e.005991b0 0x7fff8b66a380 ldap_do_free_request: asked to free lr 0x7fe82c500e70 msgid 2 refcnt 0 28149 6ac4bc0e.0059b4d8 0x7fff8b66a380 ldap_free_connection 1 1 28150 6ac4bc0e.0059d418 0x7fff8b66a380 ldap_send_unbind 28151 6ac4bc0e.0059f740 0x7fff8b66a380 ber_flush2: 7 bytes to sd 19 28152 6ac4bc0e.005ac648 0x7fff8b66a380 ldap_free_connection: actually freed 28153 280726ac4bc0e.00719620 0x7fff8b66a380 slapd stopped.
https://bugs.openldap.org/show_bug.cgi?id=10614
--- Comment #6 from Peter Dyballa Peter_Dyballa@Web.DE --- slapd.3.log has:
26832 6ac4bc0c.21b32e98 0x70000500b000 => send_search_entry: conn 1007 dn="cn=Barbara Jensen,ou=Information Technology Division,ou=People,dc=example,dc=com" 26833 6ac4bc0c.21b3af80 0x70000500b000 ber_flush2: 750 bytes to sd 30 26834 6ac4bc0c.21b42c80 0x70000500b000 <= send_search_entry: conn 1007 exit. 26835 6ac4bc0c.21b46330 0x70000500b000 => mdb_entry_decode: 26836 6ac4bc0c.21b48e28 0x70000500b000 <= mdb_entry_decode 26837 6ac4bc0c.21b4bd08 0x70000500b000 => send_search_entry: conn 1007 dn="cn=James A Jones 2,ou=Information Technology Division,ou=People,dc=example,dc=com" 26838 6ac4bc0c.21b50b28 0x70000500b000 ber_flush2: 676 bytes to sd 30 26839 6ac4bc0c.21b57888 0x70000500b000 <= send_search_entry: conn 1007 exit. 26840 6ac4bc0c.21b5b708 0x70000500b000 send_ldap_result: conn=1007 op=1 p=3 26841 6ac4bc0c.21b5e200 0x70000500b000 send_ldap_result: err=0 matched="" text="" 26842 6ac4bc0c.21b60910 0x70000500b000 send_ldap_response: msgid=2 tag=101 err=0 26843 6ac4bc0c.21b63408 0x70000500b000 ber_flush2: 14 bytes to sd 30 26844 6ac4bc0c.21b6ad20 0x70000500b000 conn=1007 op=1 SEARCH RESULT tag=101 err=0 qtime=0.000050 etime=0.002378 nentries=21 text= 26845 6ac4bc0c.21c67c00 0x700004808000 connection_get(30) 26846 6ac4bc0c.21c6e960 0x700004808000 connection_get(30): got connid=1007 26847 6ac4bc0c.21c71458 0x700004808000 connection_read(30): checking for input on id=1007 26848 6ac4bc0c.21c73b68 0x700004808000 ber_get_next 26849 6ac4bc0c.21c785a0 0x700004808000 ber_get_next: tag 0x30 len 5 contents: 26850 6ac4bc0c.21c7bc50 0x700004808000 op tag 0x42, time 1791278092 26851 6ac4bc0c.21c7eb30 0x700004808000 ber_get_next 26852 6ac4bc0c.21c825c8 0x700004808000 ber_get_next on fd 30 failed errno=0 (Undefined error: 0) 26853 6ac4bc0c.21c88b58 0x700004808000 conn=1007 op=2 do_unbind 26854 6ac4bc0c.21c8b650 0x700004808000 conn=1007 op=2 UNBIND 26855 6ac4bc0c.21c8e148 0x700004808000 connection_close: conn=1007 sd=30 26856 6ac4bc0c.21c9ac68 0x700004808000 conn=1007 fd=30 closed 26857 6ac4bc0d.0619d420 0x700004005000 daemon: shutdown requested and initiated. 26858 6ac4bc0d.061dfe88 0x700004005000 connection_close: conn=1000 sd=24 26859 6ac4bc0d.061eecd0 0x700004005000 conn=1000 fd=24 closed (slapd shutdown) 26860 6ac4bc0d.061f46a8 0x700004005000 connection_close: conn=1001 sd=25 26861 6ac4bc0d.06200610 0x700004005000 conn=1001 fd=25 closed (slapd shutdown) 26862 6ac4bc0d.06205c00 0x700004005000 connection_close: conn=1002 sd=26 26863 6ac4bc0d.06211398 0x700004005000 conn=1002 fd=26 closed (slapd shutdown) 26864 6ac4bc0d.06216d70 0x700004005000 connection_close: conn=1003 sd=27 26865 6ac4bc0d.062253e8 0x700004005000 conn=1003 fd=27 closed (slapd shutdown) 26866 6ac4bc0d.0622adc0 0x700004005000 connection_close: conn=1004 sd=28 26867 6ac4bc0d.06236558 0x700004005000 conn=1004 fd=28 closed (slapd shutdown) 26868 6ac4bc0d.0623b760 0x700004005000 connection_close: conn=1005 sd=29 26869 6ac4bc0d.0624b930 0x700004005000 conn=1005 fd=29 closed (slapd shutdown) 26870 6ac4bc0d.062568f8 0x700004005000 slapd shutdown: waiting for 0 operations/tasks to finish 26871 6ac4bc0d.063055d8 0x7fff8b66a380 slapd shutdown: initiated 26872 6ac4bc0d.06309c28 0x7fff8b66a380 conn=-1 op=0 syncprov_checkpoint: running checkpoint 26873 6ac4bc0d.0630bf50 0x7fff8b66a380 mdb_modify: dc=example,dc=com 26874 6ac4bc0d.0630f600 0x7fff8b66a380 mdb_dn2entry("dc=example,dc=com") 26875 6ac4bc0d.06311158 0x7fff8b66a380 => mdb_dn2id("dc=example,dc=com") 26876 6ac4bc0d.06314038 0x7fff8b66a380 <= mdb_dn2id: got id=0x1 26877 6ac4bc0d.06315b90 0x7fff8b66a380 => mdb_entry_decode: 26878 6ac4bc0d.06317300 0x7fff8b66a380 <= mdb_entry_decode 26879 6ac4bc0d.06318e58 0x7fff8b66a380 mdb_modify_internal: 0x00000001: dc=example,dc=com 26880 6ac4bc0d.0631c508 0x7fff8b66a380 mdb_modify_internal: replace contextCSN 26881 6ac4bc0d.0631ffa0 0x7fff8b66a380 => mdb_entry_encode(0x00000001): dc=example,dc=com 26882 6ac4bc0d.06321af8 0x7fff8b66a380 <= mdb_entry_encode(0x00000001): dc=example,dc=com 26883 6ac4bc0d.1cb6b540 0x7fff8b66a380 mdb_modify: updated id=00000001 dn="dc=example,dc=com" 26884 6ac4bc0d.1cb88a00 0x7fff8b66a380 send_ldap_result: conn=-1 op=0 p=0 26885 6ac4bc0d.1cb8c880 0x7fff8b66a380 send_ldap_result: err=0 matched="" text="" 26886 6ac4bc0d.27f9f2a0 0x7fff8b66a380 slapd destroy: freeing system resources. 26887 6ac4bc0d.27fbdae8 0x7fff8b66a380 syncinfo_free: rid=002 26888 6ac4bc0d.27fc2908 0x7fff8b66a380 connection_get(23) 26889 6ac4bc0d.27fc5fb8 0x7fff8b66a380 connection_get(23): got connid=0 26890 6ac4bc0d.27fd0f80 0x7fff8b66a380 ldap_do_free_request: asked to free lr 0x7fa62e5045c0 msgid 2 refcnt 0 26891 6ac4bc0d.27fd5da0 0x7fff8b66a380 ldap_free_connection 1 1 26892 6ac4bc0d.27fd9068 0x7fff8b66a380 ldap_send_unbind 26893 6ac4bc0d.27fde658 0x7fff8b66a380 ber_flush2: 7 bytes to sd 23 26894 6ac4bc0d.27fff5b0 0x7fff8b66a380 ldap_free_connection: actually freed 26895 6ac4bc0d.280d4008 0x7fff8b66a380 connection_get(22) 26896 6ac4bc0d.280d7aa0 0x7fff8b66a380 connection_get(22): got connid=0 26897 6ac4bc0d.280dc0f0 0x7fff8b66a380 ldap_do_free_request: asked to free lr 0x7fa62e71dc40 msgid 2 refcnt 0 26898 6ac4bc0d.280df3b8 0x7fff8b66a380 ldap_free_connection 1 1 26899 6ac4bc0d.280e1ac8 0x7fff8b66a380 ldap_send_unbind 26900 6ac4bc0d.280e49a8 0x7fff8b66a380 ber_flush2: 7 bytes to sd 22 26901 6ac4bc0d.280f14c8 0x7fff8b66a380 ldap_free_connection: actually freed 26902 6ac4bc0d.281830d0 0x7fff8b66a380 connection_get(21) 26903 6ac4bc0d.28186780 0x7fff8b66a380 connection_get(21): got connid=0 26904 6ac4bc0d.2818add0 0x7fff8b66a380 ldap_do_free_request: asked to free lr 0x7fa62e71d190 msgid 2 refcnt 0 26905 6ac4bc0d.2980cd38 0x7fff8b66a380 ldap_free_connection 1 1 26906 6ac4bc0d.29813a98 0x7fff8b66a380 ldap_send_unbind 26907 6ac4bc0d.29819858 0x7fff8b66a380 ber_flush2: 7 bytes to sd 21 26908 6ac4bc0d.298380a0 0x7fff8b66a380 ldap_free_connection: actually freed 26909 6ac4bc0d.29965108 0x7fff8b66a380 syncinfo_free: rid=012 26910 6ac4bc0d.29969b40 0x7fff8b66a380 connection_get(19) 26911 6ac4bc0d.2996c638 0x7fff8b66a380 connection_get(19): got connid=0 26912 6ac4bc0d.29971070 0x7fff8b66a380 ldap_do_free_request: asked to free lr 0x7fa62f117f60 msgid 2 refcnt 0 26913 6ac4bc0d.29974338 0x7fff8b66a380 ldap_free_connection 1 1 26914 6ac4bc0d.29976a48 0x7fff8b66a380 ldap_send_unbind 26915 6ac4bc0d.29979928 0x7fff8b66a380 ber_flush2: 7 bytes to sd 19 26916 6ac4bc0d.29986c18 0x7fff8b66a380 ldap_free_connection: actually freed 26917 6ac4bc0d.29a15d28 0x7fff8b66a380 connection_get(18) 26918 6ac4bc0d.29a197c0 0x7fff8b66a380 connection_get(18): got connid=0 26919 6ac4bc0d.29a1de10 0x7fff8b66a380 ldap_do_free_request: asked to free lr 0x7fa62f000d70 msgid 2 refcnt 0 26920 6ac4bc0d.29a210d8 0x7fff8b66a380 ldap_free_connection 1 1 26921 6ac4bc0d.29a23400 0x7fff8b66a380 ldap_send_unbind 26922 6ac4bc0d.29a266c8 0x7fff8b66a380 ber_flush2: 7 bytes to sd 18 26923 6ac4bc0d.29a32a18 0x7fff8b66a380 ldap_free_connection: actually freed 26924 6ac4bc0d.29abec48 0x7fff8b66a380 connection_get(20) 26925 6ac4bc0d.29ac26e0 0x7fff8b66a380 connection_get(20): got connid=0 26926 6ac4bc0d.29ac6948 0x7fff8b66a380 ldap_do_free_request: asked to free lr 0x7fa62e71d510 msgid 2 refcnt 0 26927 6ac4bc0d.29ac9c10 0x7fff8b66a380 ldap_free_connection 1 1 26928 6ac4bc0d.29acc320 0x7fff8b66a380 ldap_send_unbind 26929 6ac4bc0d.29acf200 0x7fff8b66a380 ber_flush2: 7 bytes to sd 20 26930 6ac4bc0d.29adb550 0x7fff8b66a380 ldap_free_connection: actually freed 26931 6ac4bc0d.29cbb500 0x7fff8b66a380 slapd stopped.
https://bugs.openldap.org/show_bug.cgi?id=10614
--- Comment #7 from Peter Dyballa Peter_Dyballa@Web.DE --- slapd.4.log has:
26250 6ac4bc0c.335a1e40 0x700010af6000 => send_search_entry: conn 1007 dn="cn=Ursula Hampster,ou=Alumni Association,ou=People,dc=example,dc=com" 26251 6ac4bc0c.335a6c60 0x700010af6000 ber_flush2: 593 bytes to sd 30 26252 6ac4bc0c.335ad5d8 0x700010af6000 <= send_search_entry: conn 1007 exit. 26253 6ac4bc0c.335b0c88 0x700010af6000 => mdb_entry_decode: 26254 6ac4bc0c.335b3398 0x700010af6000 <= mdb_entry_decode 26255 6ac4bc0c.335b6278 0x700010af6000 => send_search_entry: conn 1007 dn="cn=James A Jones 2,ou=Alumni Association,ou=People,dc=example,dc=com" 26256 6ac4bc0c.335bb480 0x700010af6000 ber_flush2: 677 bytes to sd 30 26257 6ac4bc0c.335c1df8 0x700010af6000 <= send_search_entry: conn 1007 exit. 26258 6ac4bc0c.335c54a8 0x700010af6000 => mdb_entry_decode: 26259 6ac4bc0c.335c7bb8 0x700010af6000 <= mdb_entry_decode 26260 6ac4bc0c.335caa98 0x700010af6000 => send_search_entry: conn 1007 dn="cn=Server 3 Test,dc=example,dc=com" 26261 6ac4bc0c.335ce148 0x700010af6000 ber_flush2: 93 bytes to sd 30 26262 6ac4bc0c.335d4ac0 0x700010af6000 <= send_search_entry: conn 1007 exit. 26263 6ac4bc0c.335d7d88 0x700010af6000 => mdb_entry_decode: 26264 6ac4bc0c.335da498 0x700010af6000 <= mdb_entry_decode 26265 6ac4bc0c.335e0a28 0x700010af6000 => send_search_entry: conn 1007 dn="cn=Add-Mod-Del,dc=example,dc=com" 26266 6ac4bc0c.335e44c0 0x700010af6000 ber_flush2: 101 bytes to sd 30 26267 6ac4bc0c.335eb608 0x700010af6000 <= send_search_entry: conn 1007 exit. 26268 6ac4bc0c.335ee8d0 0x700010af6000 send_ldap_result: conn=1007 op=1 p=3 26269 6ac4bc0c.335f0fe0 0x700010af6000 send_ldap_result: err=0 matched="" text="" 26270 6ac4bc0c.335f36f0 0x700010af6000 send_ldap_response: msgid=2 tag=101 err=0 26271 6ac4bc0c.335f65d0 0x700010af6000 ber_flush2: 14 bytes to sd 30 26272 6ac4bc0c.335fd718 0x700010af6000 conn=1007 op=1 SEARCH RESULT tag=101 err=0 qtime=0.000053 etime=0.004148 nentries=22 text= 26273 6ac4bc0c.337026e0 0x70000d2e1000 connection_get(30) 26274 6ac4bc0c.33709440 0x70000d2e1000 connection_get(30): got connid=1007 26275 6ac4bc0c.3370bf38 0x70000d2e1000 connection_read(30): checking for input on id=1007 26276 6ac4bc0c.3370e260 0x70000d2e1000 ber_get_next 26277 6ac4bc0c.33713080 0x70000d2e1000 ber_get_next: tag 0x30 len 5 contents: 26278 6ac4bc0c.33716348 0x70000d2e1000 op tag 0x42, time 1791278092 26279 6ac4bc0c.33719228 0x70000d2e1000 ber_get_next 26280 6ac4bc0c.3371c8d8 0x70000d2e1000 ber_get_next on fd 30 failed errno=0 (Undefined error: 0) 26281 6ac4bc0c.33722a80 0x70000d2e1000 conn=1007 op=2 do_unbind 26282 6ac4bc0c.33725190 0x70000d2e1000 conn=1007 op=2 UNBIND 26283 6ac4bc0c.33727c88 0x70000d2e1000 connection_close: conn=1007 sd=30 26284 6ac4bc0c.33734b90 0x70000d2e1000 conn=1007 fd=30 closed 26285 6ac4bc0d.0619f360 0x70000c2db000 daemon: shutdown requested and initiated. 26286 6ac4bc0d.061fa468 0x70000c2db000 connection_close: conn=1000 sd=24 26287 6ac4bc0d.06209e68 0x70000c2db000 conn=1000 fd=24 closed (slapd shutdown) 26288 6ac4bc0d.0620fc28 0x70000c2db000 connection_close: conn=1001 sd=25 26289 6ac4bc0d.0621c360 0x70000c2db000 conn=1001 fd=25 closed (slapd shutdown) 26290 6ac4bc0d.06221568 0x70000c2db000 connection_close: conn=1002 sd=26 26291 6ac4bc0d.0622c530 0x70000c2db000 conn=1002 fd=26 closed (slapd shutdown) 26292 6ac4bc0d.062355b8 0x70000c2db000 connection_close: conn=1003 sd=27 26293 6ac4bc0d.062428a8 0x70000c2db000 conn=1003 fd=27 closed (slapd shutdown) 26294 6ac4bc0d.06247e98 0x70000c2db000 connection_close: conn=1004 sd=28 26295 6ac4bc0d.06268238 0x70000c2db000 conn=1004 fd=28 closed (slapd shutdown) 26296 6ac4bc0d.0626dc10 0x70000c2db000 connection_close: conn=1005 sd=29 26297 6ac4bc0d.06279f60 0x70000c2db000 conn=1005 fd=29 closed (slapd shutdown) 26298 6ac4bc0d.06285ae0 0x70000c2db000 slapd shutdown: waiting for 2 operations/tasks to finish 26299 6ac4bc0d.091af2a8 0x70000cade000 mdb_modify: updated id=00000016 dn="cn=Add-Mod-Del,dc=example,dc=com" 26300 6ac4bc0d.091c7178 0x70000cade000 send_ldap_result: conn=-1 op=0 p=0 26301 6ac4bc0d.091cc768 0x70000cade000 send_ldap_result: err=0 matched="" text="" 26302 6ac4bc0d.091d9a58 0x70000cade000 slap_graduate_commit_csn: removing 0x7fe958e43230 20261006091443.710168Z#000000#001#000000 26303 6ac4bc0d.091dffe8 0x70000cade000 syncrepl_entry: rid=013 be_modify cn=Add-Mod-Del,dc=example,dc=com (0) 26304 6ac4bc0d.091eb780 0x70000cade000 slap_queue_csn: queueing 0x7fe958e45700 20261006091443.710168Z#000000#001#000000 26305 6ac4bc0d.091f01b8 0x70000cade000 mdb_modify: dc=example,dc=com 26306 6ac4bc0d.091f9240 0x70000cade000 mdb_dn2entry("dc=example,dc=com") 26307 6ac4bc0d.091fc8f0 0x70000cade000 => mdb_dn2id("dc=example,dc=com") 26308 6ac4bc0d.092022c8 0x70000cade000 <= mdb_dn2id: got id=0x1 26309 6ac4bc0d.09206918 0x70000cade000 => mdb_entry_decode: 26310 6ac4bc0d.0920a3b0 0x70000cade000 <= mdb_entry_decode 26311 6ac4bc0d.0920e230 0x70000cade000 mdb_modify_internal: 0x00000001: dc=example,dc=com 26312 6ac4bc0d.09216700 0x70000cade000 mdb_modify_internal: replace contextCSN 26313 6ac4bc0d.0921efb8 0x70000cade000 => mdb_entry_encode(0x00000001): dc=example,dc=com 26314 6ac4bc0d.092239f0 0x70000cade000 <= mdb_entry_encode(0x00000001): dc=example,dc=com 26315 6ac4bc0d.1ed46138 0x70000cade000 mdb_modify: updated id=00000001 dn="dc=example,dc=com" 26316 6ac4bc0d.1ed58e00 0x70000cade000 send_ldap_result: conn=-1 op=0 p=0 26317 6ac4bc0d.1ed5cc80 0x70000cade000 send_ldap_result: err=0 matched="" text="" 26318 6ac4bc0d.1ed62e28 0x70000cade000 slap_graduate_commit_csn: removing 0x7fe958e45700 20261006091443.710168Z#000000#001#000000 26319 6ac4bc0d.1ed72828 0x70000cade000 ldap_msgfree 26320 6ac4bc0d.1ed77648 0x70000cade000 ldap_result ld 0x7fe959a22dc0 msgid 2 26321 6ac4bc0d.1ed78200 0x70000dae4000 do_syncrep2: rid=011 CSN too old, ignoring 20261006091443.710168Z#000000#001#000000 (cn=Add-Mod-Del,dc=example,dc=com) 26322 6ac4bc0d.216b8ea8 0x70000cade000 wait4msg ld 0x7fe959a22dc0 msgid 2 (timeout 0 usec) 26323 6ac4bc0d.216ba230 0x70000dae4000 ldap_msgfree 26324 6ac4bc0d.216c4a28 0x70000cade000 wait4msg continue ld 0x7fe959a22dc0 msgid 2 all 0 26325 6ac4bc0d.216d01c0 0x70000cade000 ** ld 0x7fe959a22dc0 Connections: 26326 6ac4bc0d.216d4810 0x70000cade000 * host: localhost port: 9013 (default) 26327 6ac4bc0d.216da9b8 0x70000cade000 * from: IP=[::1]:53299 26328 6ac4bc0d.216de838 0x70000cade000 refcnt: 2 status: Connected 26329 6ac4bc0d.216e5d68 0x70000cade000 last used: Tue Oct 6 11:14:17 2026 26330 26331 6ac4bc0d.216e9030 0x70000cade000 26332 6ac4bc0d.216ebf10 0x70000cade000 ** ld 0x7fe959a22dc0 Outstanding Requests: 26333 6ac4bc0d.216ef1d8 0x70000cade000 * msgid 2, origid 2, status InProgress 26334 6ac4bc0d.216f20b8 0x70000cade000 outstanding referrals 0, parent count 0 26335 6ac4bc0d.216f4f98 0x70000cade000 ld 0x7fe959a22dc0 request count 1 (abandoned 0) 26336 6ac4bc0d.216f7e78 0x70000cade000 ** ld 0x7fe959a22dc0 Response Queue: 26337 6ac4bc0d.216fa970 0x70000cade000 Empty 26338 6ac4bc0d.216fd468 0x70000cade000 ld 0x7fe959a22dc0 response count 0 26339 6ac4bc0d.21700730 0x70000cade000 ldap_chkResponseList ld 0x7fe959a22dc0 msgid 2 all 0 26340 6ac4bc0d.21703228 0x70000cade000 ldap_chkResponseList returns ld 0x7fe959a22dc0 NULL 26341 6ac4bc0d.217064f0 0x70000cade000 ldap_int_select 26342 6ac4bc0d.2170f578 0x70000cade000 read1msg: ld 0x7fe959a22dc0 msgid 2 all 0 26343 6ac4bc0d.21713bc8 0x70000cade000 ber_get_next 26344 6ac4bc0d.2171dfd8 0x70000cade000 ber_get_next: tag 0x30 len 158 contents: 26345 6ac4bc0d.21722240 0x70000cade000 ldap_find_request_by_msgid: msgid 2, lr 0x7fe959800440 lr->lr_refcnt = 1 26346 6ac4bc0d.217258f0 0x70000cade000 read1msg: ld 0x7fe959a22dc0 msgid 2 message type search-entry 26347 6ac4bc0d.217287d0 0x70000cade000 ldap_return_request: lrx 0x7fe959800440, lr 0x7fe959800440 26348 6ac4bc0d.2172ba98 0x70000cade000 ldap_return_request: lrx->lr_msgid 2, lrx->lr_refcnt is now 0, lr is still present 26349 6ac4bc0d.217300e8 0x70000cade000 ldap_msgfree 26350 6ac4bc0d.21733f68 0x70000cade000 connection_get(18) 26351 6ac4bc0d.21737230 0x70000cade000 connection_get(18): got connid=0 26352 6ac4bc0d.21741258 0x70000cade000 ldap_do_free_request: asked to free lr 0x7fe959800440 msgid 2 refcnt 0 26353 6ac4bc0d.21746460 0x70000cade000 ldap_free_connection 1 1 26354 6ac4bc0d.21749b10 0x70000cade000 ldap_send_unbind 26355 6ac4bc0d.2174e930 0x70000cade000 ber_flush2: 7 bytes to sd 18 26356 6ac4bc0d.217677a0 0x70000cade000 ldap_free_connection: actually freed 26357 6ac4bc0d.2176f0b8 0x70000cade000 do_syncrepl: rid=013 rc -100 quitting 26358 6ac4bc0d.217e0d08 0x7fff8b66a380 slapd shutdown: initiated 26359 6ac4bc0d.217ea560 0x7fff8b66a380 conn=-1 op=0 syncprov_checkpoint: running checkpoint 26360 6ac4bc0d.217ee3e0 0x7fff8b66a380 mdb_modify: dc=example,dc=com 26361 6ac4bc0d.217f68b0 0x7fff8b66a380 mdb_dn2entry("dc=example,dc=com") 26362 6ac4bc0d.217f9f60 0x7fff8b66a380 => mdb_dn2id("dc=example,dc=com") 26363 6ac4bc0d.217ffd20 0x7fff8b66a380 <= mdb_dn2id: got id=0x1 26364 6ac4bc0d.21804370 0x7fff8b66a380 => mdb_entry_decode: 26365 6ac4bc0d.21807e08 0x7fff8b66a380 <= mdb_entry_decode 26366 6ac4bc0d.2180bc88 0x7fff8b66a380 mdb_modify_internal: 0x00000001: dc=example,dc=com 26367 6ac4bc0d.21814540 0x7fff8b66a380 mdb_modify_internal: replace contextCSN 26368 6ac4bc0d.2181ca10 0x7fff8b66a380 => mdb_entry_encode(0x00000001): dc=example,dc=com 26369 6ac4bc0d.21821060 0x7fff8b66a380 <= mdb_entry_encode(0x00000001): dc=example,dc=com 26370 6ac4bc0d.31fd7c40 0x7fff8b66a380 mdb_modify: updated id=00000001 dn="dc=example,dc=com" 26371 6ac4bc0d.31fea908 0x7fff8b66a380 send_ldap_result: conn=-1 op=0 p=0 26372 6ac4bc0d.31fee3a0 0x7fff8b66a380 send_ldap_result: err=0 matched="" text="" 26373 6ac4bc0d.35298ee0 0x7fff8b66a380 slapd destroy: freeing system resources. 26374 6ac4bc0d.352aa820 0x7fff8b66a380 syncinfo_free: rid=002 26375 6ac4bc0d.352ae6a0 0x7fff8b66a380 connection_get(21) 26376 6ac4bc0d.352b0db0 0x7fff8b66a380 connection_get(21): got connid=0 26377 6ac4bc0d.352b6f58 0x7fff8b66a380 ldap_do_free_request: asked to free lr 0x7fe9598004d0 msgid 2 refcnt 0 26378 6ac4bc0d.352bdcb8 0x7fff8b66a380 ldap_free_connection 1 1 26379 6ac4bc0d.352c0b98 0x7fff8b66a380 ldap_send_unbind 26380 6ac4bc0d.352c4a18 0x7fff8b66a380 ber_flush2: 7 bytes to sd 21 26381 6ac4bc0d.352da1d8 0x7fff8b66a380 ldap_free_connection: actually freed 26382 6ac4bc0d.35374e68 0x7fff8b66a380 connection_get(22) 26383 6ac4bc0d.35378518 0x7fff8b66a380 connection_get(22): got connid=0 26384 6ac4bc0d.3537cb68 0x7fff8b66a380 ldap_do_free_request: asked to free lr 0x7fe958f07d80 msgid 2 refcnt 0 26385 6ac4bc0d.3537fa48 0x7fff8b66a380 ldap_free_connection 1 1 26386 6ac4bc0d.35382158 0x7fff8b66a380 ldap_send_unbind 26387 6ac4bc0d.35385038 0x7fff8b66a380 ber_flush2: 7 bytes to sd 22 26388 6ac4bc0d.35391f40 0x7fff8b66a380 ldap_free_connection: actually freed 26389 6ac4bc0d.35428d50 0x7fff8b66a380 connection_get(23) 26390 6ac4bc0d.3542c400 0x7fff8b66a380 connection_get(23): got connid=0 26391 6ac4bc0d.35430668 0x7fff8b66a380 ldap_do_free_request: asked to free lr 0x7fe959a3c490 msgid 2 refcnt 0 26392 6ac4bc0d.35433930 0x7fff8b66a380 ldap_free_connection 1 1 26393 6ac4bc0d.35435c58 0x7fff8b66a380 ldap_send_unbind 26394 6ac4bc0d.35438b38 0x7fff8b66a380 ber_flush2: 7 bytes to sd 23 26395 6ac4bc0d.35444e88 0x7fff8b66a380 ldap_free_connection: actually freed 26396 6ac4bc0d.35535a18 0x7fff8b66a380 syncinfo_free: rid=012 26397 6ac4bc0d.3553a068 0x7fff8b66a380 connection_get(19) 26398 6ac4bc0d.3553c778 0x7fff8b66a380 connection_get(19): got connid=0 26399 6ac4bc0d.355409e0 0x7fff8b66a380 ldap_do_free_request: asked to free lr 0x7fe959b00560 msgid 2 refcnt 0 26400 6ac4bc0d.35543ca8 0x7fff8b66a380 ldap_free_connection 1 1 26401 6ac4bc0d.35545fd0 0x7fff8b66a380 ldap_send_unbind 26402 6ac4bc0d.35548eb0 0x7fff8b66a380 ber_flush2: 7 bytes to sd 19 26403 6ac4bc0d.355555e8 0x7fff8b66a380 ldap_free_connection: actually freed 26404 6ac4bc0d.3565b938 0x7fff8b66a380 connection_get(20) 26405 6ac4bc0d.3565e818 0x7fff8b66a380 connection_get(20): got connid=0 26406 6ac4bc0d.35661ae0 0x7fff8b66a380 ldap_do_free_request: asked to free lr 0x7fe959a3cf60 msgid 2 refcnt 0 26407 6ac4bc0d.35665960 0x7fff8b66a380 ldap_free_connection 1 1 26408 6ac4bc0d.356678a0 0x7fff8b66a380 ldap_send_unbind 26409 6ac4bc0d.35669bc8 0x7fff8b66a380 ber_flush2: 7 bytes to sd 20 26410 6ac4bc0d.35677a70 0x7fff8b66a380 ldap_free_connection: actually freed 26411 6ac4bc0d.357df070 0x7fff8b66a380 slapd stopped.
-
openldap-its＠openldap.org