Bug 1105245 - Segmentation Fault Starting dirsrv
Summary: Segmentation Fault Starting dirsrv
Keywords:
Status: CLOSED WORKSFORME
Alias: None
Product: Fedora
Classification: Fedora
Component: 389-ds
Version: 20
Hardware: x86_64
OS: Linux
unspecified
high
Target Milestone: ---
Assignee: Rich Megginson
QA Contact: Fedora Extras Quality Assurance
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2014-06-05 16:35 UTC by Thornton Prime
Modified: 2017-01-10 00:12 UTC (History)
9 users (show)

Fixed In Version:
Clone Of:
Environment:
389-ds-base-1.3.2.16-1.fc20.x86_64 389-ds-base-debuginfo-1.3.2.16-1.fc20.x86_64 389-ds-base-libs-1.3.2.16-1.fc20.x86_64 freeipa-admintools-3.3.5-1.fc20.x86_64 freeipa-client-3.3.5-1.fc20.x86_64 freeipa-debuginfo-3.3.5-1.fc20.x86_64 freeipa-python-3.3.5-1.fc20.x86_64 freeipa-server-3.3.5-1.fc20.x86_64
Last Closed: 2015-06-29 20:58:10 UTC
Type: Bug
Embargoed:

Attachments (Terms of Use)
Core file for failed dirsrv process (1.06 MB, application/x-gzip)
2014-06-05 16:35 UTC, Thornton Prime 		no flags Details
Core file of slapd run in debug (1.07 MB, application/x-gzip)
2014-06-05 16:43 UTC, Thornton Prime 		no flags Details
Valgrind log results (2.55 MB, text/plain)
2014-07-02 20:24 UTC, Thornton Prime 		no flags Details
Another slapd log (2.17 MB, text/plain)
2014-07-02 20:56 UTC, Thornton Prime 		no flags Details
Yet Another Valgrind Trace (2.16 MB, text/plain)
2014-07-02 21:12 UTC, Thornton Prime 		no flags Details
View All

Description Thornton Prime 2014-06-05 16:35:42 UTC 
Created attachment 902594 [details]
Core file for failed dirsrv process

Description of problem:

Starting IPA fails.

Dirsrv fails to start due to segmentation fault.

Jun  5 09:12:41 lanka kernel: [ 1331.776295] ns-slapd[1081]: segfault at 2b ip 00007fe32279c65c sp 00007fffdaac6d70 error 4 in libback-ldbm.so[7fe322779000+9e000]
Jun  5 09:12:41 lanka kernel: ns-slapd[1081]: segfault at 2b ip 00007fe32279c65c sp 00007fffdaac6d70 error 4 in libback-ldbm.so[7fe322779000+9e000]
Jun  5 09:12:41 lanka systemd: dirsrv: main process exited, code=killed, status=11/SEGV
Jun  5 09:12:41 lanka systemd: Unit dirsrv entered failed state.

Version-Release number of selected component (if applicable):


How reproducible:

Every time.

Steps to Reproduce:
1. "systemctl start dirsrv"
2.
3.

Actual results:

SEGV

Expected results:

Starts without errors.

Additional info:

Backtrace ---- core files attached


#0  dblayer_txn_init (li=<optimized out>, txn=txn@entry=0x7fff2320f210) at ldap/servers/slapd/back-ldbm/dblayer.c:3565
#1  0x00007f2748e03070 in ldbm_back_search (pb=0x7f27559d9760) at ldap/servers/slapd/back-ldbm/ldbm_search.c:360
#2  0x00007f275451ebc0 in op_shared_search (pb=pb@entry=0x7f27559d9760, send_result=send_result@entry=1) at ldap/servers/slapd/opshared.c:803
#3  0x00007f275452c4fe in search_internal_callback_pb (pb=pb@entry=0x7f27559d9760, callback_data=callback_data@entry=0x7fff232158e0,
    prc=prc@entry=0x0, psec=psec@entry=0x7f274704d9b0 <backend_shr_set_entry_cb>, prec=prec@entry=0x0) at ldap/servers/slapd/plugin_internal_op.c:812
#4  0x00007f275452ca79 in slapi_search_internal_callback_pb (pb=pb@entry=0x7f27559d9760, callback_data=callback_data@entry=0x7fff232158e0,
    prc=prc@entry=0x0, psec=psec@entry=0x7f274704d9b0 <backend_shr_set_entry_cb>, prec=prec@entry=0x0) at ldap/servers/slapd/plugin_internal_op.c:593
#5  0x00007f2747051ce8 in backend_shr_set_config_entry_add (state=0x7f27557cd050, parent_pb=0x7f275593c4c0, e=e@entry=0x7f27557a4e50,
    group=<optimized out>, set=<optimized out>) at back-shr.c:493
#6  0x00007f274704ce5d in backend_set_config_entry_add_cb (e=0x7f27557a4e50, callback_data=0x7fff2321c0a0) at back-sch.c:691
#7  0x00007f275453c84d in send_ldap_search_entry_ext (pb=pb@entry=0x7f275593c4c0, e=<optimized out>, ectrls=ectrls@entry=0x0, attrs=0x0,
    attrsonly=0, send_result=send_result@entry=0, nentries=nentries@entry=0, urls=urls@entry=0x0) at ldap/servers/slapd/result.c:1524
#8  0x00007f275453d08c in send_ldap_search_entry (pb=pb@entry=0x7f275593c4c0, e=<optimized out>, ectrls=ectrls@entry=0x0, attrs=<optimized out>,
    attrsonly=<optimized out>) at ldap/servers/slapd/result.c:1080
#9  0x00007f275451d3e1 in iterate (pb=pb@entry=0x7f275593c4c0, pnentries=pnentries@entry=0x7fff23215cd8, pagesize=pagesize@entry=-1,
    pr_statp=pr_statp@entry=0x7fff23215c54, be=0x7f275593c4c0, send_result=1) at ldap/servers/slapd/opshared.c:1444
#10 0x00007f275451d8aa in send_results_ext (pb=pb@entry=0x7f275593c4c0, nentries=nentries@entry=0x7fff23215cd8, pagesize=-1,
    pr_stat=pr_stat@entry=0x7fff23215c54, send_result=1) at ldap/servers/slapd/opshared.c:1682
#11 0x00007f275451efc1 in op_shared_search (pb=pb@entry=0x7f275593c4c0, send_result=send_result@entry=1) at ldap/servers/slapd/opshared.c:853
#12 0x00007f275452c4fe in search_internal_callback_pb (pb=pb@entry=0x7f275593c4c0, callback_data=callback_data@entry=0x7fff2321c0a0,
    prc=prc@entry=0x0, psec=0x7f274704cde0 <backend_set_config_entry_add_cb>, prec=prec@entry=0x0) at ldap/servers/slapd/plugin_internal_op.c:812
#13 0x00007f275452ca79 in slapi_search_internal_callback_pb (pb=pb@entry=0x7f275593c4c0, callback_data=callback_data@entry=0x7fff2321c0a0,
    prc=prc@entry=0x0, psec=<optimized out>, prec=prec@entry=0x0) at ldap/servers/slapd/plugin_internal_op.c:593
#14 0x00007f274705237b in backend_shr_startup (state=0x7f27557cd050, parent_pb=parent_pb@entry=0x7f2755927948,
    filter=filter@entry=0x7f2747060e20 "(&(schema-compat-container-group=*)(schema-compat-search-base=*)(schema-compat-search-filter=*)(schema-compat-entry-rdn=*))") at back-shr.c:702
#15 0x00007f274704d3d5 in backend_startup (pb=pb@entry=0x7f2755927948, state=<optimized out>) at back-sch.c:1814
#16 0x00007f274705e6a6 in plugin_startup (pb=0x7f2755927948) at plug-sch.c:110
#17 0x00007f2754528095 in plugin_call_func (list=0x7f27557ce1c0, operation=operation@entry=212, pb=0x7f2755927948, call_one=call_one@entry=1)
    at ldap/servers/slapd/plugin.c:1489
#18 0x00007f2754528c64 in plugin_call_one (pb=<optimized out>, operation=212, list=<optimized out>) at ldap/servers/slapd/plugin.c:1457
#19 plugin_dependency_startall (argc=3, argv=0x7fff2321cdf8, errmsg=<optimized out>, operation=212) at ldap/servers/slapd/plugin.c:1229
#20 0x00007f27549e2bcd in main (argc=3, argv=0x7fff2321cdf8) at ldap/servers/slapd/main.c:1187
Comment 1 Thornton Prime 2014-06-05 16:39:38 UTC 
I've started up the directory server in debug mode ( "ns-slapd -D /etc/dirsrv/slapd-ANATMAN-ORG -d 1")

It is spewing a lot of messages to the console, and it's been going for about 30 minutes, but so far has not segfaulted.
Comment 2 Thornton Prime 2014-06-05 16:42:41 UTC 
Running in debug mode di finally result in a core file. Attached as core.1304.gz

Here are the last messages up to the SEGV

[05/Jun/2014:09:39:57 -0700] - => send_ldap_search_entry (cn=memberOf,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:39:57 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:39:57 -0700] - => send_ldap_search_entry (cn=nscpEntryDN,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:39:57 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:39:57 -0700] - => send_ldap_search_entry (cn=nsds5ReplConflict,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:39:57 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:39:57 -0700] - => send_ldap_search_entry (cn=nsuniqueid,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:39:57 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:39:57 -0700] - => send_ldap_search_entry (cn=ntUniqueId,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:39:57 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:39:57 -0700] - => send_ldap_search_entry (cn=ntUserDomainId,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:39:57 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:39:57 -0700] - => send_ldap_search_entry (cn=numsubordinates,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:39:57 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:39:57 -0700] - => send_ldap_search_entry (cn=objectclass,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:39:57 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:39:57 -0700] - => send_ldap_search_entry (cn=owner,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:39:57 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:39:57 -0700] - => send_ldap_search_entry (cn=parentid,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:39:57 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:39:57 -0700] - => send_ldap_search_entry (cn=seeAlso,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:39:57 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:39:57 -0700] - => send_ldap_search_entry (cn=sn,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:39:58 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:39:58 -0700] - => send_ldap_search_entry (cn=telephoneNumber,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:39:58 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:39:58 -0700] - => send_ldap_search_entry (cn=uid,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:39:58 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:39:58 -0700] - => send_ldap_search_entry (cn=uniquemember,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:39:58 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:39:58 -0700] - => send_ldap_result 0::
[05/Jun/2014:09:39:58 -0700] - <= send_ldap_result
[05/Jun/2014:09:39:58 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:39:58 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:39:58 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:39:58 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:39:58 -0700] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=-1
[05/Jun/2014:09:39:58 -0700] - <= slapi_reslimit_get_integer_limit() returning NO VALUE
[05/Jun/2014:09:39:58 -0700] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=-1
[05/Jun/2014:09:39:58 -0700] - <= slapi_reslimit_get_integer_limit() returning NO VALUE
[05/Jun/2014:09:39:58 -0700] - => compute_limits: sizelimit=-1, timelimit=-1
[05/Jun/2014:09:39:58 -0700] - => send_ldap_result 0::
[05/Jun/2014:09:39:58 -0700] - <= send_ldap_result
[05/Jun/2014:09:39:58 -0700] - => str2entry_fast
[05/Jun/2014:09:39:58 -0700] - <= str2entry_fast 0x136a4240
[05/Jun/2014:09:39:58 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:39:58 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:39:58 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:39:59 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:39:59 -0700] - add_created_attrs
[05/Jun/2014:09:39:59 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:39:59 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:39:59 -0700] - <= send_ldap_result
[05/Jun/2014:09:39:59 -0700] - Database config entry [cn=monitor,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:39:59 -0700] - => str2entry_fast
[05/Jun/2014:09:39:59 -0700] - <= str2entry_fast 0x136a83c0
[05/Jun/2014:09:39:59 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:39:59 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:39:59 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:39:59 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:39:59 -0700] - add_created_attrs
[05/Jun/2014:09:39:59 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:39:59 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:39:59 -0700] - <= send_ldap_result
[05/Jun/2014:09:39:59 -0700] - Database config entry [cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:39:59 -0700] - => str2entry_fast
[05/Jun/2014:09:39:59 -0700] - <= str2entry_fast 0x136a9d90
[05/Jun/2014:09:39:59 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:39:59 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:39:59 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:39:59 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:39:59 -0700] - add_created_attrs
[05/Jun/2014:09:39:59 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:00 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:00 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:00 -0700] - Database config entry [cn=encrypted attributes,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:00 -0700] - => str2entry_fast
[05/Jun/2014:09:40:00 -0700] - <= str2entry_fast 0x138864d0
[05/Jun/2014:09:40:00 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:00 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:00 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:00 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:00 -0700] - add_created_attrs
[05/Jun/2014:09:40:00 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:00 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:00 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:00 -0700] - Database config entry [cn=encrypted attribute keys,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:00 -0700] - => str2entry_fast
[05/Jun/2014:09:40:00 -0700] - <= str2entry_fast 0x1386d7d0
[05/Jun/2014:09:40:00 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:00 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:00 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:00 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:00 -0700] - add_created_attrs
[05/Jun/2014:09:40:00 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:00 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:00 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:00 -0700] - Database config entry [cn=entryrdn,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:01 -0700] - => str2entry_fast
[05/Jun/2014:09:40:01 -0700] - <= str2entry_fast 0x13870120
[05/Jun/2014:09:40:01 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:01 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:01 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:01 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:01 -0700] - add_created_attrs
[05/Jun/2014:09:40:01 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:01 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:01 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:01 -0700] - Database config entry [cn=parentid,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:01 -0700] - => str2entry_fast
[05/Jun/2014:09:40:01 -0700] - <= str2entry_fast 0x1386dab0
[05/Jun/2014:09:40:01 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:01 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:01 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:01 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:01 -0700] - add_created_attrs
[05/Jun/2014:09:40:01 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:01 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:01 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:01 -0700] - Database config entry [cn=objectclass,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:01 -0700] - => str2entry_fast
[05/Jun/2014:09:40:01 -0700] - <= str2entry_fast 0x138954f0
[05/Jun/2014:09:40:01 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:01 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:02 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:02 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:02 -0700] - add_created_attrs
[05/Jun/2014:09:40:02 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:02 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:02 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:02 -0700] - Database config entry [cn=aci,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:02 -0700] - => str2entry_fast
[05/Jun/2014:09:40:02 -0700] - <= str2entry_fast 0x136a83c0
[05/Jun/2014:09:40:02 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:02 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:02 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:02 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:02 -0700] - add_created_attrs
[05/Jun/2014:09:40:02 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:02 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:02 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:02 -0700] - Database config entry [cn=numsubordinates,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:02 -0700] - => str2entry_fast
[05/Jun/2014:09:40:02 -0700] - <= str2entry_fast 0x138864d0
[05/Jun/2014:09:40:02 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:02 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:02 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:02 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:02 -0700] - add_created_attrs
[05/Jun/2014:09:40:03 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:03 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:03 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:03 -0700] - Database config entry [cn=nsuniqueid,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:03 -0700] - => str2entry_fast
[05/Jun/2014:09:40:03 -0700] - <= str2entry_fast 0x13894a00
[05/Jun/2014:09:40:03 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:03 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:03 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:03 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:03 -0700] - add_created_attrs
[05/Jun/2014:09:40:03 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:03 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:03 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:03 -0700] - Database config entry [cn=nsds5ReplConflict,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:03 -0700] - => str2entry_fast
[05/Jun/2014:09:40:03 -0700] - <= str2entry_fast 0x13871820
[05/Jun/2014:09:40:03 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:03 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:03 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:03 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:03 -0700] - add_created_attrs
[05/Jun/2014:09:40:03 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:03 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:03 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:04 -0700] - Database config entry [cn=nscpEntryDN,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:04 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:04 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:04 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:04 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:04 -0700] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=-1
[05/Jun/2014:09:40:04 -0700] - <= slapi_reslimit_get_integer_limit() returning NO VALUE
[05/Jun/2014:09:40:04 -0700] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=-1
[05/Jun/2014:09:40:04 -0700] - <= slapi_reslimit_get_integer_limit() returning NO VALUE
[05/Jun/2014:09:40:04 -0700] - => compute_limits: sizelimit=-1, timelimit=-1
[05/Jun/2014:09:40:04 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:04 -0700] - => send_ldap_search_entry (cn=USN,cn=plugins,cn=config)
[05/Jun/2014:09:40:04 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:40:04 -0700] - => send_ldap_result 0::
[05/Jun/2014:09:40:04 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:04 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:04 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:04 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:04 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:04 -0700] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=-1
[05/Jun/2014:09:40:04 -0700] - <= slapi_reslimit_get_integer_limit() returning NO VALUE
[05/Jun/2014:09:40:04 -0700] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=-1
[05/Jun/2014:09:40:04 -0700] - <= slapi_reslimit_get_integer_limit() returning NO VALUE
[05/Jun/2014:09:40:05 -0700] - => compute_limits: sizelimit=-1, timelimit=-1
[05/Jun/2014:09:40:05 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:05 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:05 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:05 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:05 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:05 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:05 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:05 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:05 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:05 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:05 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:05 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:05 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:05 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:05 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:05 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:05 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:05 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:05 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:05 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:05 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:05 -0700] - => send_ldap_search_entry (cn=aci,cn=default indexes,cn=config,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:40:05 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:40:05 -0700] - => send_ldap_search_entry (cn=cn,cn=default indexes,cn=config,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:40:05 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:40:05 -0700] - => send_ldap_search_entry (cn=entryusn,cn=default indexes,cn=config,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:40:06 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:40:06 -0700] - => send_ldap_search_entry (cn=givenName,cn=default indexes,cn=config,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:40:06 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:40:06 -0700] - => send_ldap_search_entry (cn=mail,cn=default indexes,cn=config,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:40:06 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:40:06 -0700] - => send_ldap_search_entry (cn=mailAlternateAddress,cn=default indexes,cn=config,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:40:06 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:40:06 -0700] - => send_ldap_search_entry (cn=mailHost,cn=default indexes,cn=config,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:40:06 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:40:06 -0700] - => send_ldap_search_entry (cn=member,cn=default indexes,cn=config,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:40:06 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:40:06 -0700] - => send_ldap_search_entry (cn=memberOf,cn=default indexes,cn=config,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:40:06 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:40:06 -0700] - => send_ldap_search_entry (cn=nsUniqueId,cn=default indexes,cn=config,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:40:06 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:40:06 -0700] - => send_ldap_search_entry (cn=ntUniqueId,cn=default indexes,cn=config,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:40:06 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:40:06 -0700] - => send_ldap_search_entry (cn=ntUserDomainId,cn=default indexes,cn=config,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:40:06 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:40:06 -0700] - => send_ldap_search_entry (cn=numsubordinates,cn=default indexes,cn=config,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:40:06 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:40:06 -0700] - => send_ldap_search_entry (cn=objectclass,cn=default indexes,cn=config,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:40:06 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:40:06 -0700] - => send_ldap_search_entry (cn=owner,cn=default indexes,cn=config,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:40:07 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:40:07 -0700] - => send_ldap_search_entry (cn=parentid,cn=default indexes,cn=config,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:40:07 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:40:07 -0700] - => send_ldap_search_entry (cn=seeAlso,cn=default indexes,cn=config,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:40:07 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:40:07 -0700] - => send_ldap_search_entry (cn=sn,cn=default indexes,cn=config,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:40:07 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:40:07 -0700] - => send_ldap_search_entry (cn=telephoneNumber,cn=default indexes,cn=config,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:40:07 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:40:07 -0700] - => send_ldap_search_entry (cn=uid,cn=default indexes,cn=config,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:40:07 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:40:07 -0700] - => send_ldap_search_entry (cn=uniquemember,cn=default indexes,cn=config,cn=ldbm database,cn=plugins,cn=config)
[05/Jun/2014:09:40:07 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:40:07 -0700] - => send_ldap_result 0::
[05/Jun/2014:09:40:07 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:07 -0700] - => str2entry_fast
[05/Jun/2014:09:40:07 -0700] - <= str2entry_fast 0x136a4240
[05/Jun/2014:09:40:07 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:07 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:07 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:07 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:07 -0700] - add_created_attrs
[05/Jun/2014:09:40:07 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:08 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:08 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:08 -0700] - Database config entry [cn=uniquemember,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:08 -0700] - => str2entry_fast
[05/Jun/2014:09:40:08 -0700] - <= str2entry_fast 0x1388ee80
[05/Jun/2014:09:40:08 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:08 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:08 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:08 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:08 -0700] - add_created_attrs
[05/Jun/2014:09:40:08 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:08 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:08 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:08 -0700] - Database config entry [cn=uid,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:08 -0700] - => str2entry_fast
[05/Jun/2014:09:40:08 -0700] - <= str2entry_fast 0x1388e340
[05/Jun/2014:09:40:08 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:08 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:08 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:08 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:08 -0700] - add_created_attrs
[05/Jun/2014:09:40:08 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:08 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:08 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:09 -0700] - Database config entry [cn=telephoneNumber,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:09 -0700] - => str2entry_fast
[05/Jun/2014:09:40:09 -0700] - <= str2entry_fast 0x138864d0
[05/Jun/2014:09:40:09 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:09 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:09 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:09 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:09 -0700] - add_created_attrs
[05/Jun/2014:09:40:09 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:09 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:09 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:09 -0700] - Database config entry [cn=sn,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:09 -0700] - => str2entry_fast
[05/Jun/2014:09:40:09 -0700] - <= str2entry_fast 0x138954f0
[05/Jun/2014:09:40:09 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:09 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:09 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:09 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:09 -0700] - add_created_attrs
[05/Jun/2014:09:40:09 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:09 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:09 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:09 -0700] - Database config entry [cn=seeAlso,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:09 -0700] - => str2entry_fast
[05/Jun/2014:09:40:10 -0700] - <= str2entry_fast 0x1388fd50
[05/Jun/2014:09:40:10 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:10 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:10 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:10 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:10 -0700] - add_created_attrs
[05/Jun/2014:09:40:10 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:10 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:10 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:10 -0700] - Database config entry [cn=parentid,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:10 -0700] - => str2entry_fast
[05/Jun/2014:09:40:10 -0700] - <= str2entry_fast 0x136a9d90
[05/Jun/2014:09:40:10 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:10 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:10 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:10 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:10 -0700] - add_created_attrs
[05/Jun/2014:09:40:10 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:10 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:10 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:10 -0700] - Database config entry [cn=owner,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:10 -0700] - => str2entry_fast
[05/Jun/2014:09:40:10 -0700] - <= str2entry_fast 0x1387be00
[05/Jun/2014:09:40:10 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:10 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:10 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:11 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:11 -0700] - add_created_attrs
[05/Jun/2014:09:40:11 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:11 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:11 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:11 -0700] - Database config entry [cn=objectclass,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:11 -0700] - => str2entry_fast
[05/Jun/2014:09:40:11 -0700] - <= str2entry_fast 0x1387f610
[05/Jun/2014:09:40:11 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:11 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:11 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:11 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:11 -0700] - add_created_attrs
[05/Jun/2014:09:40:11 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:11 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:11 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:11 -0700] - Database config entry [cn=numsubordinates,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:11 -0700] - => slapi_attr_get_value_cmp_fn
[05/Jun/2014:09:40:11 -0700] - <= slapi_attr_get_value_cmp_fn
[05/Jun/2014:09:40:11 -0700] - => str2entry_fast
[05/Jun/2014:09:40:11 -0700] - <= str2entry_fast 0x1388ee80
[05/Jun/2014:09:40:11 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:11 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:11 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:11 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:11 -0700] - add_created_attrs
[05/Jun/2014:09:40:11 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:12 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:12 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:12 -0700] - Database config entry [cn=ntUserDomainId,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:12 -0700] - => str2entry_fast
[05/Jun/2014:09:40:12 -0700] - <= str2entry_fast 0x13878490
[05/Jun/2014:09:40:12 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:12 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:12 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:12 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:12 -0700] - add_created_attrs
[05/Jun/2014:09:40:12 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:12 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:12 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:12 -0700] - Database config entry [cn=ntUniqueId,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:12 -0700] - => str2entry_fast
[05/Jun/2014:09:40:12 -0700] - <= str2entry_fast 0x1386d7d0
[05/Jun/2014:09:40:12 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:12 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:12 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:12 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:12 -0700] - add_created_attrs
[05/Jun/2014:09:40:12 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:12 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:12 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:12 -0700] - Database config entry [cn=nsUniqueId,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:13 -0700] - => str2entry_fast
[05/Jun/2014:09:40:13 -0700] - <= str2entry_fast 0x138954f0
[05/Jun/2014:09:40:13 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:13 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:13 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:13 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:13 -0700] - add_created_attrs
[05/Jun/2014:09:40:13 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:13 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:13 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:13 -0700] - Database config entry [cn=memberOf,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:13 -0700] - => str2entry_fast
[05/Jun/2014:09:40:13 -0700] - <= str2entry_fast 0x1388fd50
[05/Jun/2014:09:40:13 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:13 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:13 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:13 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:13 -0700] - add_created_attrs
[05/Jun/2014:09:40:13 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:13 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:13 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:13 -0700] - Database config entry [cn=member,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:13 -0700] - => str2entry_fast
[05/Jun/2014:09:40:13 -0700] - <= str2entry_fast 0x135e92e0
[05/Jun/2014:09:40:13 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:14 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:14 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:14 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:14 -0700] - add_created_attrs
[05/Jun/2014:09:40:14 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:14 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:14 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:14 -0700] - Database config entry [cn=mailHost,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:14 -0700] - => str2entry_fast
[05/Jun/2014:09:40:14 -0700] - <= str2entry_fast 0x1387be00
[05/Jun/2014:09:40:14 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:14 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:14 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:14 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:14 -0700] - add_created_attrs
[05/Jun/2014:09:40:14 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:14 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:14 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:14 -0700] - Database config entry [cn=mailAlternateAddress,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:14 -0700] - => str2entry_fast
[05/Jun/2014:09:40:14 -0700] - <= str2entry_fast 0x1387f610
[05/Jun/2014:09:40:14 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:14 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:14 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:15 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:15 -0700] - add_created_attrs
[05/Jun/2014:09:40:15 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:15 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:15 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:15 -0700] - Database config entry [cn=mail,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:15 -0700] - => str2entry_fast
[05/Jun/2014:09:40:15 -0700] - <= str2entry_fast 0x1388ee80
[05/Jun/2014:09:40:15 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:15 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:15 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:15 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:15 -0700] - add_created_attrs
[05/Jun/2014:09:40:15 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:15 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:15 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:15 -0700] - Database config entry [cn=givenName,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:15 -0700] - => str2entry_fast
[05/Jun/2014:09:40:15 -0700] - <= str2entry_fast 0x138790a0
[05/Jun/2014:09:40:15 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:15 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:15 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:15 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:16 -0700] - add_created_attrs
[05/Jun/2014:09:40:16 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:16 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:16 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:16 -0700] - Database config entry [cn=entryusn,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:16 -0700] - => slapi_attr_get_value_cmp_fn
[05/Jun/2014:09:40:16 -0700] - <= slapi_attr_get_value_cmp_fn
[05/Jun/2014:09:40:16 -0700] - => str2entry_fast
[05/Jun/2014:09:40:16 -0700] - <= str2entry_fast 0x13893f40
[05/Jun/2014:09:40:16 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:16 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:16 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:16 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:16 -0700] - add_created_attrs
[05/Jun/2014:09:40:16 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:16 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:16 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:16 -0700] - Database config entry [cn=cn,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:16 -0700] - => str2entry_fast
[05/Jun/2014:09:40:16 -0700] - <= str2entry_fast 0x13896280
[05/Jun/2014:09:40:16 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:16 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:16 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:16 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:17 -0700] - add_created_attrs
[05/Jun/2014:09:40:17 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:17 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:17 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:17 -0700] - Database config entry [cn=aci,cn=index,cn=changelog,cn=ldbm database,cn=plugins,cn=config] already exists - skipping
[05/Jun/2014:09:40:17 -0700] - => slapi_reslimit_register() attrname=nsLookThroughLimit
[05/Jun/2014:09:40:17 -0700] - <= slapi_reslimit_register() returning status=0, handle=0
[05/Jun/2014:09:40:17 -0700] - => slapi_reslimit_register() attrname=nsIDListScanLimit
[05/Jun/2014:09:40:17 -0700] - <= slapi_reslimit_register() returning status=0, handle=1
[05/Jun/2014:09:40:17 -0700] - => slapi_reslimit_register() attrname=nsPagedLookThroughLimit
[05/Jun/2014:09:40:17 -0700] - <= slapi_reslimit_register() returning status=0, handle=2
[05/Jun/2014:09:40:17 -0700] - => slapi_reslimit_register() attrname=nsPagedIDListScanLimit
[05/Jun/2014:09:40:17 -0700] - <= slapi_reslimit_register() returning status=0, handle=3
[05/Jun/2014:09:40:17 -0700] - => slapi_reslimit_register() attrname=nsRangeSearchLookThroughLimit
[05/Jun/2014:09:40:17 -0700] - <= slapi_reslimit_register() returning status=0, handle=4
[05/Jun/2014:09:40:17 -0700] - mkdir_p /var/lib/dirsrv/slapd-ANATMAN-ORG/db: error -5966 (Access Denied.)
[05/Jun/2014:09:40:17 -0700] - Can't start because the database directory "/var/lib/dirsrv/slapd-ANATMAN-ORG/db" either doesn't exist, or is not accessible
[05/Jun/2014:09:40:17 -0700] - start: Failed to init database, err=-1 BDB0092 Unknown error: -1
[05/Jun/2014:09:40:17 -0700] - Failed to start database plugin ldbm database
[05/Jun/2014:09:40:17 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:17 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:17 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:17 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:17 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:17 -0700] - add_created_attrs
[05/Jun/2014:09:40:17 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:17 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:18 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:18 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:18 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:18 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:18 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:18 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:18 -0700] - add_created_attrs
[05/Jun/2014:09:40:18 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:18 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:18 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:18 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:18 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:18 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:18 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:18 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:18 -0700] - add_created_attrs
[05/Jun/2014:09:40:18 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:18 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:18 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:18 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:18 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:18 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:18 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:18 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:18 -0700] - add_created_attrs
[05/Jun/2014:09:40:19 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:19 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:19 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:19 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:19 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:19 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:19 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:19 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:19 -0700] - add_created_attrs
[05/Jun/2014:09:40:19 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:19 -0700] - => send_ldap_result 68::
[05/Jun/2014:09:40:19 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:19 -0700] - Calling plugin 'Schema Compatibility' #0 type 212
[05/Jun/2014:09:40:19 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:19 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:19 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:19 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:19 -0700] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=-1
[05/Jun/2014:09:40:19 -0700] - <= slapi_reslimit_get_integer_limit() returning NO VALUE
[05/Jun/2014:09:40:19 -0700] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=-1
[05/Jun/2014:09:40:19 -0700] - <= slapi_reslimit_get_integer_limit() returning NO VALUE
[05/Jun/2014:09:40:19 -0700] - => compute_limits: sizelimit=-1, timelimit=-1
[05/Jun/2014:09:40:19 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:20 -0700] - => send_ldap_search_entry (cn=Schema Compatibility,cn=plugins,cn=config)
[05/Jun/2014:09:40:20 -0700] - <= send_ldap_search_entry
[05/Jun/2014:09:40:20 -0700] - => send_ldap_result 0::
[05/Jun/2014:09:40:20 -0700] - <= send_ldap_result
[05/Jun/2014:09:40:20 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:20 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:20 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:20 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:20 -0700] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=-1
[05/Jun/2014:09:40:20 -0700] - <= slapi_reslimit_get_integer_limit() returning NO VALUE
[05/Jun/2014:09:40:20 -0700] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=-1
[05/Jun/2014:09:40:20 -0700] - <= slapi_reslimit_get_integer_limit() returning NO VALUE
[05/Jun/2014:09:40:20 -0700] - => compute_limits: sizelimit=-1, timelimit=-1
[05/Jun/2014:09:40:20 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:20 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:20 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:20 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:20 -0700] - pw_copy_entry_ext: source pw_entry_extension is not set
[05/Jun/2014:09:40:20 -0700] - => send_ldap_search_entry (cn=computers,cn=Schema Compatibility,cn=plugins,cn=config)
[05/Jun/2014:09:40:20 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12)
[05/Jun/2014:09:40:20 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:20 -0700] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18)
[05/Jun/2014:09:40:20 -0700] - <= slapi_control_present 0 (NO CONTROLS)
[05/Jun/2014:09:40:20 -0700] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=-1
[05/Jun/2014:09:40:21 -0700] - <= slapi_reslimit_get_integer_limit() returning NO VALUE
[05/Jun/2014:09:40:21 -0700] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=-1
[05/Jun/2014:09:40:21 -0700] - <= slapi_reslimit_get_integer_limit() returning NO VALUE
[05/Jun/2014:09:40:21 -0700] - => compute_limits: sizelimit=-1, timelimit=-1
Comment 3 Thornton Prime 2014-06-05 16:43:28 UTC 
Created attachment 902595 [details]
Core file of slapd run in debug
Comment 4 Rob Crittenden 2014-06-05 17:25:15 UTC 
Re-assigning to 389-ds team.
Comment 5 Noriko Hosoi 2014-06-05 19:04:48 UTC 
The frame #16 and up to #14 are from the NIS/Schema compat plugin.

#14 0x00007f274705237b in backend_shr_startup (state=0x7f27557cd050, parent_pb=parent_pb@entry=0x7f2755927948,
    filter=filter@entry=0x7f2747060e20 "(&(schema-compat-container-group=*)(schema-compat-search-base=*)(schema-compat-search-filter=*)(schema-compat-entry-rdn=*))") at back-shr.c:702
#15 0x00007f274704d3d5 in backend_startup (pb=pb@entry=0x7f2755927948, state=<optimized out>) at back-sch.c:1814
#16 0x00007f274705e6a6 in plugin_startup (pb=0x7f2755927948) at plug-sch.c:110
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
#17 0x00007f2754528095 in plugin_call_func (list=0x7f27557ce1c0, operation=operation@entry=212, pb=0x7f2755927948, call_one=call_one@entry=1)
    at ldap/servers/slapd/plugin.c:1489
#18 0x00007f2754528c64 in plugin_call_one (pb=<optimized out>, operation=212, list=<optimized out>) at ldap/servers/slapd/plugin.c:1457
#19 plugin_dependency_startall (argc=3, argv=0x7fff2321cdf8, errmsg=<optimized out>, operation=212) at ldap/servers/slapd/plugin.c:1229
#20 0x00007f27

The location where the crash occurred is the line 3565:
#0  dblayer_txn_init (li=<optimized out>, txn=txn@entry=0x7fff2320f210) at ldap/servers/slapd/back-ldbm/dblayer.c:3565

3559 int dblayer_txn_init(struct ldbminfo *li, back_txn *txn)
3560 {
3561     back_txn *cur_txn = dblayer_get_pvt_txn();
3562     PR_ASSERT(NULL != txn);
3563 
3564     if (cur_txn && txn) {
3565         txn->back_txn_txn = cur_txn->back_txn_txn;
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
3566     } else if (txn) {

It looks to me the current transaction (cur_txn) returned by dblayer_get_pvt_txn might be a garbage (not sure why though).

I'm wondering if this is a F20 specific problem?
Comment 6 Thornton Prime 2014-06-05 22:21:03 UTC 
I was able to get service restored by reverting to a backup of my database. Whatever the corruption is there, it happened in the last week (because my nightly backup didn't work).

I have the database files stashed that caused the problem, but because they have user account credentials in there, I prefer not upload the tar.gz of the database files.

Are there any tools I can run that can help identify the database records that are suspected of causing the SEGV?


Because this points back to a database corruption issue, I think we can lower severity from HIGH to MEDIUM, but I don't appear to have permissions to do that.
Comment 7 Noriko Hosoi 2014-06-06 16:54:40 UTC 
Hello Thornton, could you also add the version of slapi-nis to Environment?
$ rpm -q slapi-nis
Thanks.
Comment 8 Noriko Hosoi 2014-06-06 17:12:31 UTC 
(In reply to Thornton Prime from comment #6)
> Are there any tools I can run that can help identify the database records
> that are suspected of causing the SEGV?

It looks to me the cause of the crash is a memory issue, not data...  Could it be possible to run the Directory Server (ns-slapd) via valgrind?

After running "yum install valgrind", could you make this change to /usr/sbin/start-dirsrv and run ""systemctl start dirsrv"?  The output is found in /tmp/slapd.$$.out.
================================================================
# diff -twU4 /usr/sbin/start-dirsrv.orig /usr/sbin/start-dirsrv
--- /usr/sbin/start-dirsrv.orig	2014-06-05 17:21:22.000000000 -0700
+++ /usr/sbin/start-dirsrv	2014-05-29 15:54:43.954975299 -0700
@@ -63,19 +63,20 @@
     #
     # Use systemctl if available and running as root, 
     # otherwise start the instance the old way.
     #
-    if [ -d "/usr/lib/systemd/system" ] && [ "$(id -u)" == "0" ];then
-        /usr/bin/systemctl start dirsrv@$SERV_ID.service
+#    if [ -d "/usr/lib/systemd/system" ] && [ "$(id -u)" == "0" ];then
+#        /usr/bin/systemctl start dirsrv@$SERV_ID.service
+#        if [ $? -ne 0 ]; then
+#            return 1
+#        fi
+#    else
+        export USE_VALGRIND=1
+        cd $SERVERBIN_DIR; valgrind --log-file=/tmp/slapd.$$.out --num-callers=32 --tool=memcheck --leak-check=full --show-reachable=yes --leak-resolution=high ./ns-slapd -D $CONFIG_DIR -i $PIDFILE -w $STARTPIDFILE "$@" -d 0
         if [ $? -ne 0 ]; then
             return 1
         fi
-    else
-        cd $SERVERBIN_DIR; ./ns-slapd -D $CONFIG_DIR -i $PIDFILE -w $STARTPIDFILE "$@"
-        if [ $? -ne 0 ]; then
-            return 1
-        fi
-    fi
+#    fi
     loop_counter=1
     # wait for 10 seconds for the start pid file to appear
     max_count=${STARTPID_TIME:-10}
     while test $loop_counter -le $max_count; do
================================================================
Thank you for your help.
Comment 9 Noriko Hosoi 2014-06-24 17:26:30 UTC 
Hello Thornton,

Did you have a chance to run the server with valgrind?

Thanks.
Comment 10 Thornton Prime 2014-07-02 20:24:14 UTC 
Created attachment 914297 [details]
Valgrind log results

Sorry it took so long.

I needed to wait until I could get an environment to duplicate this bug in, but unfortunately it started happening again in production and now I can't get back my IPA server by reverting to a backup.

Attached valgrind log.
Comment 11 Rich Megginson 2014-07-02 20:29:46 UTC 
(In reply to Thornton Prime from comment #10)
> Created attachment 914297 [details]
> Valgrind log results
> 
> Sorry it took so long.
> 
> I needed to wait until I could get an environment to duplicate this bug in,
> but unfortunately it started happening again in production and now I can't
> get back my IPA server by reverting to a backup.
> 
> Attached valgrind log.

Thanks, but unfortunately there are some missing symbols.  This is a new environment?  Looks like there are some debuginfo packages missing.  Please run the following:

# debuginfo-install 389-ds-base freeipa-server ipa-server bind-dyndb-ldap slapi-nis

Then re-run valgrind, and we should get a better report with all of the symbols.
Comment 12 Thornton Prime 2014-07-02 20:56:37 UTC 
Created attachment 914303 [details]
Another slapd log
Comment 13 Thornton Prime 2014-07-02 20:58:34 UTC 
Another valgrind slapd log attached, hopefully this one has more symbols.

This failure was on my primary IPA server, and being down is causing all sorts of problems with my DNS and other infrastructure.

I can see yum can no longer download all the debuginfo packages, but I can't figure out why it works for some and not others yet.
Comment 14 Rich Megginson 2014-07-02 21:02:31 UTC 
hmm - it still cannot find the slapi-nis symbols.  Make sure the version of the slapi-nis package exactly matches the version of the slapi-nis-debuginfo package e.g.

$ rpm -q slapi-nis slapi-nis-debuginfo
Comment 15 Thornton Prime 2014-07-02 21:12:46 UTC 
Created attachment 914304 [details]
Yet Another Valgrind Trace

OK, re-ran after manually downloading and installing the slapi-nis-debuginfo

# rpm -q slapi-nis slapi-nis-debuginfo
slapi-nis-0.52-1.fc20.x86_64
slapi-nis-debuginfo-0.52-1.fc20.x86_64
Comment 16 Rich Megginson 2014-07-02 21:17:47 UTC 
Looks good.  Now all we have to do is fix it . . .
Comment 17 Noriko Hosoi 2014-07-10 00:10:36 UTC 
Hello Thornton,

Is it possible to share your config file /etc/dirsrv/slapd-YOURID/dse.ldif with us?  I'm interested in the plugin configurations.

It looks to me the NIS or Schema Compat Plugin is started prior to the backend plugin...
Comment 18 Rich Megginson 2014-09-26 13:40:19 UTC 
Can we close this issue now?  I'm hoping it has been fixed by an update to 389 and/or slapi-nis.
Comment 19 Noriko Hosoi 2014-11-04 18:58:34 UTC 
This bug has no updates for a while.  Can we have a status?  Do you still experience the segmentation fault?  Thanks.
Comment 20 Fedora End Of Life 2015-05-29 12:02:14 UTC 
This message is a reminder that Fedora 20 is nearing its end of life.
Approximately 4 (four) weeks from now Fedora will stop maintaining
and issuing updates for Fedora 20. It is Fedora's policy to close all
bug reports from releases that are no longer maintained. At that time
this bug will be closed as EOL if it remains open with a Fedora  'version'
of '20'.

Package Maintainer: If you wish for this bug to remain open because you
plan to fix it in a currently maintained version, simply change the 'version' 
to a later Fedora version.

Thank you for reporting this issue and we are sorry that we were not 
able to fix it before Fedora 20 is end of life. If you would still like 
to see this bug fixed and are able to reproduce it against a later version 
of Fedora, you are encouraged  change the 'version' to a later Fedora 
version prior this bug is closed as described in the policy above.

Although we aim to fix as many bugs as possible during every release's 
lifetime, sometimes those efforts are overtaken by events. Often a 
more recent Fedora release includes newer upstream software that fixes 
bugs or makes them obsolete.
Comment 21 Fedora End Of Life 2015-06-29 20:58:10 UTC 
Fedora 20 changed to end-of-life (EOL) status on 2015-06-23. Fedora 20 is
no longer maintained, which means that it will not receive any further
security or bug fix updates. As a result we are closing this bug.

If you can reproduce this bug against a currently maintained version of
Fedora please feel free to reopen this bug against that version. If you
are unable to reopen this bug, please file a new report against the
current release. If you experience problems, please add a comment to this
bug.

Thank you for reporting this bug and we are sorry it could not be fixed.
Comment 22 Thornton Prime 2017-01-10 00:00:39 UTC 
Can not duplicate.

Close
Comment 23 Noriko Hosoi 2017-01-10 00:12:06 UTC 
(In reply to Thornton Prime from comment #22)
> Can not duplicate.
> 
> Close

Thank you for verifying the bug.
Best regards.
Note You need to log in before you can comment on or make changes to this bug.