[Date Prev][Date Next] [Chronological] [Thread] [Top]
password change takes 20 seconds

To: openldap-software@OpenLDAP.org
Subject: password change takes 20 seconds
From: thomas.emde@scaleon.de
Date: Fri, 15 Feb 2002 10:40:23 +0100
Sensitivity:
Hello,

I am using openldap server 2.0.21 and have a little database with one
organization unit and one test user.
I can login to my system as this test user and now try to change his password by
using ldappasswd. Actually
it works but it takes about 20 (!!!) seconds to perform this action. Here is a
snippet from the messages file
which shows a lot of changes in the ldbm database which take a long long time.
Can anyone tell what is happening
there and why?

snip

Feb 15 10:28:50 linuxhost slapd[909]: => index_entry_del( 2, "uid=mark6,
o=myorg, c=D" )
Feb 15 10:28:50 linuxhost slapd[909]: => ldbm_cache_open
( "/var/lib/ldap/objectClass.dbb", 7, 600 )
Feb 15 10:28:50 linuxhost slapd[909]: <= ldbm_cache_open (cache 3)
Feb 15 10:28:50 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:51 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:51 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:51 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:51 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:51 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:51 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:52 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:52 linuxhost slapd[909]: => ldbm_cache_open
( "/var/lib/ldap/uid.dbb", 7, 600 )
Feb 15 10:28:52 linuxhost slapd[909]: <= ldbm_cache_open (cache 4)
Feb 15 10:28:52 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:52 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:52 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:52 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:52 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:53 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:53 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:53 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:53 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:53 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:53 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:54 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:54 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:54 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:54 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:54 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:54 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:55 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:55 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:55 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:55 linuxhost slapd[909]: => ldbm_cache_open
( "/var/lib/ldap/cn.dbb", 7, 600 )
Feb 15 10:28:55 linuxhost slapd[909]: <= ldbm_cache_open (cache 5)
Feb 15 10:28:55 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:55 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:55 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:56 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:56 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:56 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:56 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:56 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:56 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:57 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:57 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:57 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:57 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:57 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:57 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:57 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:57 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:58 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:58 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:58 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:58 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:58 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:58 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:59 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:59 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:59 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:59 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:28:59 linuxhost slapd[909]: <= key_change 0
Feb 15 10:28:59 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:29:00 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:00 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:29:00 linuxhost slapd[909]: <= key_change -1
Feb 15 10:29:00 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:29:00 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:00 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:29:01 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:01 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:29:01 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:01 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:29:01 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:01 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:29:02 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:02 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:29:02 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:02 linuxhost slapd[909]: => key_change(DELETE,2)
Feb 15 10:29:02 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:02 linuxhost slapd[909]: <= index_entry_del( 2, "uid=mark6,
o=myorg, c=D" ) success
Feb 15 10:29:02 linuxhost slapd[909]: => index_entry_add( 2, "uid=mark6,
o=myorg, c=D" )
Feb 15 10:29:02 linuxhost slapd[909]: => ldbm_cache_open
( "/var/lib/ldap/objectClass.dbb", 7, 600 )
Feb 15 10:29:02 linuxhost slapd[909]: <= ldbm_cache_open (cache 3)
Feb 15 10:29:02 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:02 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:02 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:03 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:03 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:03 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:03 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:03 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:03 linuxhost slapd[909]: => ldbm_cache_open
( "/var/lib/ldap/uid.dbb", 7, 600 )
Feb 15 10:29:03 linuxhost slapd[909]: <= ldbm_cache_open (cache 4)
Feb 15 10:29:03 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:03 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:03 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:04 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:04 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:04 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:04 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:04 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:04 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:04 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:04 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:04 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:04 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:05 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:05 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:05 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:05 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:05 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:05 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:06 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:06 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:06 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:06 linuxhost slapd[909]: => ldbm_cache_open
( "/var/lib/ldap/cn.dbb", 7, 600 )
Feb 15 10:29:06 linuxhost slapd[909]: <= ldbm_cache_open (cache 5)
Feb 15 10:29:06 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:06 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:06 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:06 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:06 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:07 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:07 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:07 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:07 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:07 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:07 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:07 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:07 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:08 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:08 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:08 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:08 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:08 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:08 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:08 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:08 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:09 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:09 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:09 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:09 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:09 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:09 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:09 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:09 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:10 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:10 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:10 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:10 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:10 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:10 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:10 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:10 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:11 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:11 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:11 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:11 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:11 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:11 linuxhost slapd[909]: => key_change(ADD,2)
Feb 15 10:29:11 linuxhost slapd[909]: <= key_change 0
Feb 15 10:29:12 linuxhost slapd[909]: <= index_entry_add( 2, "uid=mark6,
o=myorg, c=D" ) success
Feb 15 10:29:12 linuxhost slapd[909]: => id2entry_add( 2, "uid=mark6, o=myorg,
c=D" )
Feb 15 10:29:12 linuxhost slapd[909]: => ldbm_cache_open
( "/var/lib/ldap/id2entry.dbb", 7, 600 )
Feb 15 10:29:12 linuxhost slapd[909]: <= ldbm_cache_open (cache 1)

snip

best regards,
Thomas
Follow-Ups:
- RE: password change takes 20 seconds
  - From: "Reinhard Proessler" <reinhard.proessler@hamur.hanjin.com>
- RE: password change takes 20 seconds
  - From: "Howard Chu" <hyc@highlandsun.com>
Prev by Date: Re: assertion failure in liblber sockbuf.c
Next by Date: Re: ACL for userPassword problems
Index(es):
- Chronological
- Thread