[Date Prev][Date Next] [Chronological] [Thread] [Top]

Re: tests fail with 2.1.13



Hi,
 I have set the tests to debug=-1 now and attach the log output from
failed test007, which ist replication

Dieter Kluenter <dieter@dkluenter.de> writes:

> Hi,
> I'm testing OpenLDAP-2.1.3 with BerkeleyDB-4.1.25 but the test suit
> fails randomly at various stages, that ist, sometimes it is test003,
> or test007 or test015, but mostly it is test007

that should read OpenLDAP-2.1.13 :-(
>
> -.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-
> ...
> Using ldapsearch to read all the entries from the slave...
> Filtering ldapsearch results...
> ./scripts/test007-replication: ./test-db/master.out: Interrupted
> system call
>>>>>> ./scripts/test007-replication failed (exit 1)
> make: *** [test-bdb] Error 1
> -.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.
>
> and the logmessages
> -.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.--.-.-
> Feb 25 18:14:56 marin lt-slapadd: bdb_initialize: Sleepycat Software: Berkeley DB 4.1.25: (December 19, 2002) 
> Feb 25 18:14:56 marin lt-slapadd: bdb_db_init: Initializing BDB database 
> Feb 25 18:14:57 marin lt-slapindex: bdb_initialize: Sleepycat Software: Berkeley DB 4.1.25: (December 19, 2002) 
> Feb 25 18:14:57 marin lt-slapindex: bdb_db_init: Initializing BDB database 
> Feb 25 18:14:57 marin lt-slapindex: => bdb_dn2id_add: put failed: DB_KEYEXIST: Key/data pair already exists -30997 
> Feb 25 18:14:57 marin last message repeated 18 times
> -.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-

-.-.-.-.-.-.master.log-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-.-
<= send_search_entry
====> bdb_cache_return_entry_r( 21 ): returned (0)
====> bdb_cache_find_entry_id( 22 ) "cn=Rosco P. Coltrane,ou=Retired,ou=People,o=University of Michigan,c=US" (found) (1 tries)
=> send_search_entry: dn="cn=Rosco P. Coltrane,ou=Retired,ou=People,o=University of Michigan,c=US"
ber_flush: 178 bytes to sd 13
<= send_search_entry
====> bdb_cache_return_entry_r( 22 ): returned (0)
send_search_result: err=0 matched="" text=""
send_ldap_response: msgid=2 tag=101 err=0
ber_flush: 14 bytes to sd 13
conn=4 op=1 SEARCH RESULT tag=101 err=0 nentries=21 text=
connection_get(13)
connection_get(13): got connid=4
connection_read(13): checking for input on id=4
ber_get_next
ber_get_next: tag 0x30 len 5 contents:
ber_get_next
ber_get_next on fd 13 failed errno=11 (Resource temporarily unavailable)
do_unbind
conn=4 op=2 UNBIND
connection_closing: readying conn=4 sd=13 for close
connection_resched: attempting closing conn=4 sd=13
connection_close: conn=4 sd=13
conn=4 fd=13 closed
slap_sig_shutdown: signal 1
daemon: shutdown requested and initiated.
slapd shutdown: waiting for 0 threads to terminate
lt-slapd shutdown: initiated
====> bdb_cache_release_all
lt-slapd shutdown: freeing system resources.
====> bdb_cache_release_all
slapd stopped.
-.-.-.-.-.-.-.end master.log-.-.-.-.-.-.-.-.-.-.-.

-.-.-.-.-.-.-.slave.log-.-.-.-.-.-.-.-.-.-.-.-.-.-
<= send_search_entry
====> bdb_cache_return_entry_r( 21 ): returned (0)
====> bdb_cache_find_entry_id( 22 ) "cn=Rosco P. Coltrane,ou=Retired,ou=People,o=University of Michigan,c=US" (found) (1 tries)
=> send_search_entry: dn="cn=Rosco P. Coltrane,ou=Retired,ou=People,o=University of Michigan,c=US"
ber_flush: 178 bytes to sd 18
<= send_search_entry
====> bdb_cache_return_entry_r( 22 ): returned (0)
send_search_result: err=0 matched="" text=""
send_ldap_response: msgid=2 tag=101 err=0
ber_flush: 14 bytes to sd 18
conn=1 op=1 SEARCH RESULT tag=101 err=0 nentries=21 text=
connection_get(18)
connection_get(18): got connid=1
connection_read(18): checking for input on id=1
ber_get_next
ber_get_next: tag 0x30 len 5 contents:
ber_get_next
ber_get_next on fd 18 failed errno=11 (Resource temporarily unavailable)
do_unbind
conn=1 op=2 UNBIND
connection_closing: readying conn=1 sd=18 for close
connection_resched: attempting closing conn=1 sd=18
connection_close: conn=1 sd=18
conn=1 fd=18 closed
slap_sig_shutdown: signal 1
daemon: shutdown requested and initiated.
connection_closing: readying conn=0 sd=13 for close
connection_close: conn=0 sd=13
conn=0 fd=13 closed
slapd shutdown: waiting for 0 threads to terminate
lt-slapd shutdown: initiated
====> bdb_cache_release_all
lt-slapd shutdown: freeing system resources.
====> bdb_cache_release_all
slapd stopped.
--.-.-.-.-.-.-.-.-.end slave.log-.-.-.-.-.-.-.-.-.-.-.-.-.

-.-.-.-.-.-.-.-.-.slurp.log.-.-.-.-.-.-.-.-.-..-.-

replica localhost:9010 - delete dn "cn=James A Jones 2,ou=Information Technology Division,ou=People,o=University of Michigan,c=US"
ldap_delete_ext
ldap_send_initial_request
ldap_send_server_request
ber_flush: 129 bytes to sd 8
ldap_result msgid 29
ldap_chkResponseList for msgid=29, all=1
ldap_chkResponseList returns NULL
wait4msg (infinite timeout), msgid 29
wait4msg continue, msgid 29, all 1
** Connections:
* host: localhost  port: 9010  (default)
  refcnt: 2  status: Connected
  last used: Tue Feb 25 22:43:19 2003

** Outstanding Requests:
 * msgid 29,  origid 29, status InProgress
   outstanding referrals 0, parent count 0
** Response Queue:
   Empty
ldap_chkResponseList for msgid=29, all=1
ldap_chkResponseList returns NULL
ldap_int_select
read1msg: msgid 29, all 1
ber_get_next
ber_get_next: tag 0x30 len 12 contents:
ldap_read: message type delete msgid 29, original id 29
ber_scanf fmt ({iaa) ber:
read1msg:  0 new referrals
read1msg:  mark request completed, id = 29
request 29 done
res_errno: 0, res_error: <>, res_matched: <>
ldap_free_request (origid 29, msgid 29)
ldap_free_connection
ldap_free_connection: refcnt 1
ldap_parse_result
ber_scanf fmt ({iaa) ber:
ber_scanf fmt (}) ber:
ldap_msgfree
-.-.-.-.-.-.-.-.-.-.end slurp.log.-.-.-.-.-.-.-.-.-.-.

-Dieter

-- 
Dieter Kluenter  | Systemberatung
Tel:040.64861967 | Fax: 040.64891521
mailto: dkluenter@schevolution.com
http://www.schevolution.com/tour