]> git.sur5r.net Git - openldap/blobdiff - servers/slapd/back-ldbm/cache.c
error message from be_entry_put tool backend function
[openldap] / servers / slapd / back-ldbm / cache.c
index b23ca2a7761e096ac1fe111bc8349137df0fb49a..1a9f1c6fcfe3006acbf68e023d20bc565479c11f 100644 (file)
@@ -1,4 +1,9 @@
 /* cache.c - routines to maintain an in-core cache of entries */
+/* $OpenLDAP$ */
+/*
+ * Copyright 1998-2002 The OpenLDAP Foundation, All Rights Reserved.
+ * COPYING RESTRICTIONS APPLY, see COPYRIGHT file
+ */
 
 #include "portable.h"
 
@@ -13,7 +18,7 @@
 #include "back-ldbm.h"
 
 /* LDBM backend specific entry info -- visible only to the cache */
-struct ldbm_entry_info {
+typedef struct ldbm_entry_info {
        ldap_pvt_thread_rdwr_t  lei_rdwr;       /* reader/writer lock */
 
        /*
@@ -24,25 +29,34 @@ struct ldbm_entry_info {
        int             lei_state;      /* for the cache */
 #define        CACHE_ENTRY_UNDEFINED   0
 #define CACHE_ENTRY_CREATING   1
-#define CACHE_ENTRY_READY              2
-#define CACHE_ENTRY_DELETED            3
-
+#define CACHE_ENTRY_READY      2
+#define CACHE_ENTRY_DELETED    3
+#define CACHE_ENTRY_COMMITTED  4
+       
        int             lei_refcnt;     /* # threads ref'ing this entry */
-       struct entry    *lei_lrunext;   /* for cache lru list */
-       struct entry    *lei_lruprev;
-};
-#define LEI(e) ((struct ldbm_entry_info *) ((e)->e_private))
+       Entry   *lei_lrunext;   /* for cache lru list */
+       Entry   *lei_lruprev;
+} EntryInfo;
+#undef LEI
+#define LEI(e) ((EntryInfo *) ((e)->e_private))
 
-static int     cache_delete_entry_internal(struct cache *cache, Entry *e);
+static int     cache_delete_entry_internal(Cache *cache, Entry *e);
 #ifdef LDAP_DEBUG
-static void    lru_print(struct cache *cache);
+static void    lru_print(Cache *cache);
 #endif
 
 static int
 cache_entry_rdwr_lock(Entry *e, int rw)
 {
+#ifdef NEW_LOGGING
+       LDAP_LOG(( "cache", LDAP_LEVEL_ENTRY,
+                  "cache_entry_rdwr_lock: %s lock on ID %ld\n",
+                  rw ? "w" : "r", e->e_id ));
+#else
        Debug( LDAP_DEBUG_ARGS, "entry_rdwr_%slock: ID: %ld\n",
                rw ? "w" : "r", e->e_id, 0);
+#endif
+
 
        if (rw)
                return ldap_pvt_thread_rdwr_wlock(&LEI(e)->lei_rdwr);
@@ -53,8 +67,15 @@ cache_entry_rdwr_lock(Entry *e, int rw)
 static int
 cache_entry_rdwr_trylock(Entry *e, int rw)
 {
+#ifdef NEW_LOGGING
+       LDAP_LOG(( "cache", LDAP_LEVEL_ENTRY,
+                  "cache_entry_rdwr_trylock: try %s lock on ID: %ld.\n",
+                  rw ? "w" : "r", e->e_id ));
+#else
        Debug( LDAP_DEBUG_ARGS, "entry_rdwr_%strylock: ID: %ld\n",
                rw ? "w" : "r", e->e_id, 0);
+#endif
+
 
        if (rw)
                return ldap_pvt_thread_rdwr_wtrylock(&LEI(e)->lei_rdwr);
@@ -65,8 +86,15 @@ cache_entry_rdwr_trylock(Entry *e, int rw)
 static int
 cache_entry_rdwr_unlock(Entry *e, int rw)
 {
+#ifdef NEW_LOGGING
+       LDAP_LOG(( "cache", LDAP_LEVEL_ENTRY,
+                  "cache_entry_rdwr_unlock: remove %s lock on ID %ld.\n",
+                  rw ? "w" : "r", e->e_id ));
+#else
        Debug( LDAP_DEBUG_ARGS, "entry_rdwr_%sunlock: ID: %ld\n",
                rw ? "w" : "r", e->e_id, 0);
+#endif
+
 
        if (rw)
                return ldap_pvt_thread_rdwr_wunlock(&LEI(e)->lei_rdwr);
@@ -89,11 +117,7 @@ cache_entry_rdwr_destroy(Entry *e)
 static int
 cache_entry_private_init( Entry*e )
 {
-       struct ldbm_entry_info *lei;
-
-#ifdef LDAP_DEBUG
        assert( e->e_private == NULL );
-#endif
 
        if( e->e_private != NULL ) {
                /* this should never happen */
@@ -111,14 +135,27 @@ cache_entry_private_init( Entry*e )
        return 0;
 }
 
+/*
+ * marks an entry in CREATING state as committed, so it is really returned
+ * to the cache. Otherwise an entry in CREATING state is removed.
+ * Makes e_private be destroyed at the following cache_return_entry_w,
+ * but lets the entry untouched (owned by someone else)
+ */
+void
+cache_entry_commit( Entry *e )
+{
+       assert( e );
+       assert( e->e_private );
+       assert( LEI(e)->lei_state == CACHE_ENTRY_CREATING );
+       /* assert( LEI(e)->lei_refcnt == 1 ); */
+
+       LEI(e)->lei_state = CACHE_ENTRY_COMMITTED;
+}
+
 static int
 cache_entry_private_destroy( Entry*e )
 {
-       struct ldbm_entry_info *lei;
-
-#ifdef LDAP_DEBUG
        assert( e->e_private );
-#endif
 
        cache_entry_rdwr_destroy( e );
 
@@ -128,76 +165,127 @@ cache_entry_private_destroy( Entry*e )
 }
 
 void
-cache_return_entry_rw( struct cache *cache, Entry *e, int rw )
+cache_return_entry_rw( Cache *cache, Entry *e, int rw )
 {
+       ID id;
+       int refcnt, freeit = 1;
+
        /* set cache mutex */
        ldap_pvt_thread_mutex_lock( &cache->c_mutex );
 
-#ifdef LDAP_DEBUG
        assert( e->e_private );
-#endif
 
        cache_entry_rdwr_unlock(e, rw);
 
-       LEI(e)->lei_refcnt--;
+       id = e->e_id;
+       refcnt = --LEI(e)->lei_refcnt;
+
+       /*
+        * if the entry is returned when in CREATING state, it is deleted
+        * but not freed because it may belong to someone else (do_add,
+        * for instance)
+        */
+       if (  LEI(e)->lei_state == CACHE_ENTRY_CREATING ) {
+               cache_delete_entry_internal( cache, e );
+               freeit = 0;
+               /* now the entry is in DELETED state */
+       }
+
+       if ( LEI(e)->lei_state == CACHE_ENTRY_COMMITTED ) {
+               LEI(e)->lei_state = CACHE_ENTRY_READY;
+
+               /* free cache mutex */
+               ldap_pvt_thread_mutex_unlock( &cache->c_mutex );
 
-       if ( LEI(e)->lei_state == CACHE_ENTRY_CREATING ) {
+#ifdef NEW_LOGGING
+               LDAP_LOG(( "cache", LDAP_LEVEL_DETAIL1,
+                          "cache_return_entry_rw: return (%ld):%s, refcnt=%d\n",
+                          id, rw ? "w" : "r", refcnt ));
+#else
                Debug( LDAP_DEBUG_TRACE,
                        "====> cache_return_entry_%s( %ld ): created (%d)\n",
-                       rw ? "w" : "r", e->e_id, LEI(e)->lei_refcnt );
+                       rw ? "w" : "r", id, refcnt );
+#endif
 
-               LEI(e)->lei_state = CACHE_ENTRY_READY;
 
        } else if ( LEI(e)->lei_state == CACHE_ENTRY_DELETED ) {
-               if( LEI(e)->lei_refcnt > 0 ) {
+               if( refcnt > 0 ) {
+                       /* free cache mutex */
+                       ldap_pvt_thread_mutex_unlock( &cache->c_mutex );
+
+#ifdef NEW_LOGGING
+                       LDAP_LOG(( "cache", LDAP_LEVEL_DETAIL1,
+                                  "cache_return_entry_rw: %ld, delete pending (%d).\n",
+                                  id, refcnt ));
+#else
                        Debug( LDAP_DEBUG_TRACE,
                                "====> cache_return_entry_%s( %ld ): delete pending (%d)\n",
-                               rw ? "w" : "r", e->e_id, LEI(e)->lei_refcnt );
+                               rw ? "w" : "r", id, refcnt );
+#endif
+
 
                } else {
+                       cache_entry_private_destroy( e );
+                       if ( freeit ) {
+                               entry_free( e );
+                       }
+
+                       /* free cache mutex */
+                       ldap_pvt_thread_mutex_unlock( &cache->c_mutex );
+
+#ifdef NEW_LOGGING
+                       LDAP_LOG(( "cache", LDAP_LEVEL_DETAIL1,
+                                  "cache_return_entry_rw: (%ld): deleted (%d)\n",
+                                  id, refcnt ));
+#else
                        Debug( LDAP_DEBUG_TRACE,
                                "====> cache_return_entry_%s( %ld ): deleted (%d)\n",
-                               rw ? "w" : "r", e->e_id, LEI(e)->lei_refcnt );
+                               rw ? "w" : "r", id, refcnt );
+#endif
 
-                       cache_entry_private_destroy( e );
-                       entry_free( e );
                }
 
        } else {
+               /* free cache mutex */
+               ldap_pvt_thread_mutex_unlock( &cache->c_mutex );
+
+#ifdef NEW_LOGGING
+               LDAP_LOG(( "cache", LDAP_LEVEL_DETAIL1,
+                          "cache_return_entry_rw: ID %ld:%s returned (%d)\n",
+                          id, rw ? "w": "r", refcnt ));
+#else
                Debug( LDAP_DEBUG_TRACE,
                        "====> cache_return_entry_%s( %ld ): returned (%d)\n",
-                       rw ? "w" : "r", e->e_id, LEI(e)->lei_refcnt);
-       }
-
+                       rw ? "w" : "r", id, refcnt);
+#endif
 
-       /* free cache mutex */
-       ldap_pvt_thread_mutex_unlock( &cache->c_mutex );
+       }
 }
 
-#define LRU_DELETE( cache, e ) { \
+#define LRU_DELETE( cache, e ) do { \
        if ( LEI(e)->lei_lruprev != NULL ) { \
                LEI(LEI(e)->lei_lruprev)->lei_lrunext = LEI(e)->lei_lrunext; \
        } else { \
-               cache->c_lruhead = LEI(e)->lei_lrunext; \
+               (cache)->c_lruhead = LEI(e)->lei_lrunext; \
        } \
        if ( LEI(e)->lei_lrunext != NULL ) { \
                LEI(LEI(e)->lei_lrunext)->lei_lruprev = LEI(e)->lei_lruprev; \
        } else { \
-               cache->c_lrutail = LEI(e)->lei_lruprev; \
+               (cache)->c_lrutail = LEI(e)->lei_lruprev; \
        } \
-}
+} while(0)
 
-#define LRU_ADD( cache, e ) { \
-       LEI(e)->lei_lrunext = cache->c_lruhead; \
+#define LRU_ADD( cache, e ) do { \
+       LEI(e)->lei_lrunext = (cache)->c_lruhead; \
        if ( LEI(e)->lei_lrunext != NULL ) { \
-               LEI(LEI(e)->lei_lrunext)->lei_lruprev = e; \
+               LEI(LEI(e)->lei_lrunext)->lei_lruprev = (e); \
        } \
-       cache->c_lruhead = e; \
+       (cache)->c_lruhead = (e); \
        LEI(e)->lei_lruprev = NULL; \
-       if ( cache->c_lrutail == NULL ) { \
-               cache->c_lrutail = e; \
+       if ( (cache)->c_lrutail == NULL ) { \
+               (cache)->c_lrutail = (e); \
        } \
-}
+} while(0)
 
 /*
  * cache_add_entry_rw - create and lock an entry in the cache
@@ -207,7 +295,7 @@ cache_return_entry_rw( struct cache *cache, Entry *e, int rw )
  */
 int
 cache_add_entry_rw(
-    struct cache       *cache,
+    Cache      *cache,
     Entry              *e,
        int             rw
 )
@@ -215,49 +303,85 @@ cache_add_entry_rw(
        int     i, rc;
        Entry   *ee;
 
+#ifdef NEW_LOGGING
+       LDAP_LOG(( "cache", LDAP_LEVEL_ENTRY,
+                  "cache_add_entry_rw: add (%s):%s to cache\n",
+                  e->e_dn, rw ? "w" : "r" ));
+#endif
        /* set cache mutex */
        ldap_pvt_thread_mutex_lock( &cache->c_mutex );
 
-#ifdef LDAP_DEBUG
        assert( e->e_private == NULL );
-#endif
 
        if( cache_entry_private_init(e) != 0 ) {
+               /* free cache mutex */
+               ldap_pvt_thread_mutex_unlock( &cache->c_mutex );
+
+#ifdef NEW_LOGGING
+               LDAP_LOG(( "cache", LDAP_LEVEL_ERR,
+                          "cache_add_entry_rw: add (%s):%ld private init failed!\n",
+                          e->e_dn, e->e_id ));
+#else
                Debug( LDAP_DEBUG_ANY,
                        "====> cache_add_entry( %ld ): \"%s\": private init failed!\n",
                    e->e_id, e->e_dn, 0 );
+#endif
+
+
                return( -1 );
        }
 
        if ( avl_insert( &cache->c_dntree, (caddr_t) e,
-               entry_dn_cmp, avl_dup_error ) != 0 )
+               (AVL_CMP) entry_dn_cmp, avl_dup_error ) != 0 )
        {
+               /* free cache mutex */
+               ldap_pvt_thread_mutex_unlock( &cache->c_mutex );
+
+#ifdef NEW_LOGGING
+               LDAP_LOG(( "cache", LDAP_LEVEL_DETAIL1,
+                          "cache_add_entry: (%s):%ld already in cache.\n",
+                          e->e_dn, e->e_id ));
+#else
                Debug( LDAP_DEBUG_TRACE,
                        "====> cache_add_entry( %ld ): \"%s\": already in dn cache\n",
                    e->e_id, e->e_dn, 0 );
+#endif
+
 
                cache_entry_private_destroy(e);
 
-               /* free cache mutex */
-               ldap_pvt_thread_mutex_unlock( &cache->c_mutex );
                return( 1 );
        }
 
        /* id tree */
        if ( avl_insert( &cache->c_idtree, (caddr_t) e,
-               entry_id_cmp, avl_dup_error ) != 0 )
+               (AVL_CMP) entry_id_cmp, avl_dup_error ) != 0 )
        {
+#ifdef NEW_LOGGING
+               LDAP_LOG(( "cache", LDAP_LEVEL_DETAIL1,
+                          "cache_add_entry: (%s):%ls already in cache.\n",
+                          e->e_dn, e->e_id ));
+#else
                Debug( LDAP_DEBUG_ANY,
                        "====> cache_add_entry( %ld ): \"%s\": already in id cache\n",
                    e->e_id, e->e_dn, 0 );
+#endif
+
 
 
                /* delete from dn tree inserted above */
                if ( avl_delete( &cache->c_dntree, (caddr_t) e,
-                       entry_dn_cmp ) == NULL )
+                       (AVL_CMP) entry_dn_cmp ) == NULL )
                {
+#ifdef NEW_LOGGING
+                       LDAP_LOG(( "cache", LDAP_LEVEL_INFO,
+                                  "cache_add_entry: can't delete (%s) from cache.\n",
+                                  e->e_dn ));
+#else
                        Debug( LDAP_DEBUG_ANY, "====> can't delete from dn cache\n",
                            0, 0, 0 );
+#endif
+
                }
 
                cache_entry_private_destroy(e);
@@ -305,6 +429,7 @@ cache_add_entry_rw(
                        e = cache->c_lrutail;
 
                        /* delete from cache and lru q */
+                       /* XXX do we need rc ? */
                        rc = cache_delete_entry_internal( cache, e );
                        cache_entry_private_destroy( e );
                        entry_free( e );
@@ -324,7 +449,7 @@ cache_add_entry_rw(
  */
 int
 cache_update_entry(
-    struct cache       *cache,
+    Cache      *cache,
     Entry              *e
 )
 {
@@ -334,16 +459,21 @@ cache_update_entry(
        /* set cache mutex */
        ldap_pvt_thread_mutex_lock( &cache->c_mutex );
 
-#ifdef LDAP_DEBUG
        assert( e->e_private );
-#endif
 
        if ( avl_insert( &cache->c_dntree, (caddr_t) e,
-               entry_dn_cmp, avl_dup_error ) != 0 )
+               (AVL_CMP) entry_dn_cmp, avl_dup_error ) != 0 )
        {
+#ifdef NEW_LOGGING
+               LDAP_LOG(( "cache", LDAP_LEVEL_DETAIL1,
+                          "cache_update_entry: (%s):%ld already in dn cache\n",
+                          e->e_dn, e->e_id ));
+#else
                Debug( LDAP_DEBUG_TRACE,
                        "====> cache_update_entry( %ld ): \"%s\": already in dn cache\n",
                    e->e_id, e->e_dn, 0 );
+#endif
+
 
                /* free cache mutex */
                ldap_pvt_thread_mutex_unlock( &cache->c_mutex );
@@ -352,18 +482,32 @@ cache_update_entry(
 
        /* id tree */
        if ( avl_insert( &cache->c_idtree, (caddr_t) e,
-               entry_id_cmp, avl_dup_error ) != 0 )
+               (AVL_CMP) entry_id_cmp, avl_dup_error ) != 0 )
        {
+#ifdef NEW_LOGGING
+               LDAP_LOG(( "cache", LDAP_LEVEL_DETAIL1,
+                          "cache_update_entry: (%s)%ld already in id cache\n",
+                          e->e_dn, e->e_id ));
+#else
                Debug( LDAP_DEBUG_ANY,
                        "====> cache_update_entry( %ld ): \"%s\": already in id cache\n",
                    e->e_id, e->e_dn, 0 );
+#endif
+
 
                /* delete from dn tree inserted above */
                if ( avl_delete( &cache->c_dntree, (caddr_t) e,
-                       entry_dn_cmp ) == NULL )
+                       (AVL_CMP) entry_dn_cmp ) == NULL )
                {
+#ifdef NEW_LOGGING
+                       LDAP_LOG(( "cache", LDAP_LEVEL_INFO,
+                                  "cache_update_entry: can't delete (%s)%ld from dn cache.\n",
+                                  e->e_dn, e->e_id ));
+#else
                        Debug( LDAP_DEBUG_ANY, "====> can't delete from dn cache\n",
                            0, 0, 0 );
+#endif
+
                }
 
                /* free cache mutex */
@@ -407,6 +551,7 @@ cache_update_entry(
                        e = cache->c_lrutail;
 
                        /* delete from cache and lru q */
+                       /* XXX do we need rc ? */
                        rc = cache_delete_entry_internal( cache, e );
                        cache_entry_private_destroy( e );
                        entry_free( e );
@@ -418,79 +563,92 @@ cache_update_entry(
        return( 0 );
 }
 
-/*
- * cache_find_entry_dn2id - find an entry in the cache, given dn
- */
-
 ID
-cache_find_entry_dn2id(
+cache_find_entry_ndn2id(
        Backend         *be,
-    struct cache       *cache,
-    char               *dn
+    Cache      *cache,
+    struct berval      *ndn
 )
 {
-       struct ldbminfo *li = (struct ldbminfo *) be->be_private;
        Entry           e, *ep;
        ID                      id;
+       int count = 0;
 
+       /* this function is always called with normalized DN */
+       e.e_nname = *ndn;
+
+try_again:
        /* set cache mutex */
        ldap_pvt_thread_mutex_lock( &cache->c_mutex );
 
-       e.e_dn = dn;
-       e.e_ndn = dn_normalize_case( ch_strdup( dn ) );
-
        if ( (ep = (Entry *) avl_find( cache->c_dntree, (caddr_t) &e,
-               entry_dn_cmp )) != NULL )
+               (AVL_CMP) entry_dn_cmp )) != NULL )
        {
+               int state;
+               count++;
+
                /*
                 * ep now points to an unlocked entry
                 * we do not need to lock the entry if we only
                 * check the state, refcnt, LRU, and id.
                 */
-               free(e.e_ndn);
 
-#ifdef LDAP_DEBUG
                assert( ep->e_private );
-#endif
+
+               /* save id */
+               id = ep->e_id;
+               state = LEI(ep)->lei_state;
+
                /*
                 * entry is deleted or not fully created yet
                 */
-               if ( LEI(ep)->lei_state != CACHE_ENTRY_READY ) {
-#ifdef LDAP_DEBUG
-                       assert(LEI(ep)->lei_state != CACHE_ENTRY_UNDEFINED);
-#endif
-                       Debug(LDAP_DEBUG_TRACE,
-                               "====> cache_find_entry_dn2id(\"%s\"): %ld (not ready) %d\n",
-                               dn, ep->e_id, LEI(ep)->lei_state);
+               if ( state != CACHE_ENTRY_READY ) {
+                       assert(state != CACHE_ENTRY_UNDEFINED);
 
                        /* free cache mutex */
                        ldap_pvt_thread_mutex_unlock( &cache->c_mutex );
-                       return( NOID );
-               }
 
-               Debug(LDAP_DEBUG_TRACE,
-                       "====> cache_find_entry_dn2id(\"%s\"): %ld\n",
-                       dn, ep->e_id, 0);
+#ifdef NEW_LOGGING
+                       LDAP_LOG(( "cache", LDAP_LEVEL_INFO,
+                                  "cache_find_entry_dn2id: (%s) %ld not ready: %d\n",
+                                  ndn->bv_val, id, state ));
+#else
+                       Debug(LDAP_DEBUG_TRACE,
+                               "====> cache_find_entry_dn2id(\"%s\"): %ld (not ready) %d\n",
+                               ndn->bv_val, id, state);
+#endif
+
+
+                       ldap_pvt_thread_yield();
+                       goto try_again;
+               }
 
                /* lru */
                LRU_DELETE( cache, ep );
                LRU_ADD( cache, ep );
-                
-               /* save id */
-               id = ep->e_id;
-
+               
                /* free cache mutex */
                ldap_pvt_thread_mutex_unlock( &cache->c_mutex );
 
-               return( id );
-       }
+#ifdef NEW_LOGGING
+               LDAP_LOG(( "cache", LDAP_LEVEL_DETAIL1,
+                          "cache_find_entry_dn2id: (%s): %ld %d tries\n",
+                          ndn->bv_val, id, count ));
+#else
+               Debug(LDAP_DEBUG_TRACE,
+                       "====> cache_find_entry_dn2id(\"%s\"): %ld (%d tries)\n",
+                       ndn->bv_val, id, count);
+#endif
 
-       free(e.e_ndn);
 
-       /* free cache mutex */
-       ldap_pvt_thread_mutex_unlock( &cache->c_mutex );
+       } else {
+               /* free cache mutex */
+               ldap_pvt_thread_mutex_unlock( &cache->c_mutex );
 
-       return( NOID );
+               id = NOID;
+       }
+
+       return( id );
 }
 
 /*
@@ -499,13 +657,14 @@ cache_find_entry_dn2id(
 
 Entry *
 cache_find_entry_id(
-       struct cache    *cache,
+       Cache   *cache,
        ID                              id,
        int                             rw
 )
 {
        Entry   e;
        Entry   *ep;
+       int     count = 0;
 
        e.e_id = id;
 
@@ -514,30 +673,43 @@ try_again:
        ldap_pvt_thread_mutex_lock( &cache->c_mutex );
 
        if ( (ep = (Entry *) avl_find( cache->c_idtree, (caddr_t) &e,
-               entry_id_cmp )) != NULL )
+               (AVL_CMP) entry_id_cmp )) != NULL )
        {
-#ifdef LDAP_DEBUG
+               int state;
+               ID      ep_id;
+
+               count++;
+
                assert( ep->e_private );
-#endif
+
+               ep_id = ep->e_id; 
+               state = LEI(ep)->lei_state;
+
                /*
                 * entry is deleted or not fully created yet
                 */
-               if ( LEI(ep)->lei_state != CACHE_ENTRY_READY ) {
-#ifdef LDAP_DEBUG
-                       assert(LEI(ep)->lei_state != CACHE_ENTRY_UNDEFINED);
-#endif
-                       Debug(LDAP_DEBUG_TRACE,
-                               "====> cache_find_entry_id( %ld ): %ld (not ready) %d\n",
-                               id, ep->e_id, LEI(ep)->lei_state);
+               if ( state != CACHE_ENTRY_READY ) {
+
+                       assert(state != CACHE_ENTRY_UNDEFINED);
 
                        /* free cache mutex */
                        ldap_pvt_thread_mutex_unlock( &cache->c_mutex );
-                       return( NULL );
-               }
 
-               Debug(LDAP_DEBUG_TRACE,
-                       "====> cache_find_entry_id( %ld, %s ) \"%s\" (found)\n",
-                       id, rw ? "w" : "r", ep->e_dn);
+#ifdef NEW_LOGGING
+                       LDAP_LOG(( "cache", LDAP_LEVEL_INFO,
+                                  "cache_find_entry_id: (%ld)->%ld not ready (%d).\n",
+                                  id, ep_id, state ));
+                                  
+#else
+                       Debug(LDAP_DEBUG_TRACE,
+                               "====> cache_find_entry_id( %ld ): %ld (not ready) %d\n",
+                               id, ep_id, state);
+#endif
+
+
+                       ldap_pvt_thread_yield();
+                       goto try_again;
+               }
 
                /* acquire reader lock */
                if ( cache_entry_rdwr_trylock(ep, rw) == LDAP_PVT_THREAD_EBUSY ) {
@@ -548,6 +720,18 @@ try_again:
 
                        /* free cache mutex */
                        ldap_pvt_thread_mutex_unlock( &cache->c_mutex );
+
+#ifdef NEW_LOGGING
+                       LDAP_LOG(( "cache", LDAP_LEVEL_INFO,
+                                  "cache_find_entry_id: %ld -> %ld (busy) %d.\n",
+                                  id, ep_id, state ));
+#else
+                       Debug(LDAP_DEBUG_TRACE,
+                               "====> cache_find_entry_id( %ld ): %ld (busy) %d\n",
+                               id, ep_id, state);
+#endif
+
+
                        ldap_pvt_thread_yield();
                        goto try_again;
                }
@@ -555,12 +739,23 @@ try_again:
                /* lru */
                LRU_DELETE( cache, ep );
                LRU_ADD( cache, ep );
-                
+               
                LEI(ep)->lei_refcnt++;
 
                /* free cache mutex */
                ldap_pvt_thread_mutex_unlock( &cache->c_mutex );
 
+#ifdef NEW_LOGGING
+               LDAP_LOG(( "cache", LDAP_LEVEL_DETAIL1,
+                          "cache_find_entry_id: %ld -> %s  found %d tries.\n",
+                          ep_id, ep->e_dn, count ));
+#else
+               Debug(LDAP_DEBUG_TRACE,
+                       "====> cache_find_entry_id( %ld ) \"%s\" (found) (%d tries)\n",
+                       ep_id, ep->e_dn, count);
+#endif
+
+
                return( ep );
        }
 
@@ -583,7 +778,7 @@ try_again:
  */
 int
 cache_delete_entry(
-    struct cache       *cache,
+    Cache      *cache,
     Entry              *e
 )
 {
@@ -592,12 +787,16 @@ cache_delete_entry(
        /* set cache mutex */
        ldap_pvt_thread_mutex_lock( &cache->c_mutex );
 
-#ifdef LDAP_DEBUG
        assert( e->e_private );
-#endif
 
+#ifdef NEW_LOGGING
+       LDAP_LOG(( "cache", LDAP_LEVEL_ENTRY,
+                  "cache_delete_entry: delete %ld.\n", e->e_id ));
+#else
        Debug( LDAP_DEBUG_TRACE, "====> cache_delete_entry( %ld )\n",
                e->e_id, 0, 0 );
+#endif
+
 
        rc = cache_delete_entry_internal( cache, e );
 
@@ -608,21 +807,21 @@ cache_delete_entry(
 
 static int
 cache_delete_entry_internal(
-    struct cache       *cache,
+    Cache      *cache,
     Entry              *e
 )
 {
-       int rc = 0;     /* return code */
+       int rc = 0;     /* return code */
 
        /* dn tree */
-       if ( avl_delete( &cache->c_dntree, (caddr_t) e, entry_dn_cmp )
+       if ( avl_delete( &cache->c_dntree, (caddr_t) e, (AVL_CMP) entry_dn_cmp )
                == NULL )
        {
                rc = -1;
        }
 
        /* id tree */
-       if ( avl_delete( &cache->c_idtree, (caddr_t) e, entry_id_cmp )
+       if ( avl_delete( &cache->c_idtree, (caddr_t) e, (AVL_CMP) entry_id_cmp )
                == NULL )
        {
                rc = -1;
@@ -644,10 +843,53 @@ cache_delete_entry_internal(
        return( 0 );
 }
 
+void
+cache_release_all( Cache *cache )
+{
+       Entry *e;
+       int rc;
+
+       /* set cache mutex */
+       ldap_pvt_thread_mutex_lock( &cache->c_mutex );
+
+#ifdef NEW_LOGGING
+       LDAP_LOG(( "cache", LDAP_LEVEL_ENTRY,
+                  "cache_release_all: enter\n" ));
+#else
+       Debug( LDAP_DEBUG_TRACE, "====> cache_release_all\n", 0, 0, 0 );
+#endif
+
+
+       while ( (e = cache->c_lrutail) != NULL && LEI(e)->lei_refcnt == 0 ) {
+#ifdef LDAP_RDWR_DEBUG
+               assert(!ldap_pvt_thread_rdwr_active(&LEI(e)->lei_rdwr));
+#endif
+
+               /* delete from cache and lru q */
+               /* XXX do we need rc ? */
+               rc = cache_delete_entry_internal( cache, e );
+               cache_entry_private_destroy( e );
+               entry_free( e );
+       }
+
+       if ( cache->c_cursize ) {
+#ifdef NEW_LOGGING
+               LDAP_LOG(( "cache", LDAP_LEVEL_INFO,
+                          "cache_release_all: Entry cache could not be emptied.\n" ));
+#else
+               Debug( LDAP_DEBUG_TRACE, "Entry-cache could not be emptied\n", 0, 0, 0 );
+#endif
+
+       }
+
+       /* free cache mutex */
+       ldap_pvt_thread_mutex_unlock( &cache->c_mutex );
+}
+
 #ifdef LDAP_DEBUG
 
 static void
-lru_print( struct cache *cache )
+lru_print( Cache *cache )
 {
        Entry   *e;