[Zope3-dev] make test failure (transaction and zeo)

Christian Heimes heimes@faho.rwth-aachen.de
Sun, 09 Mar 2003 16:29:21 +0100


python2.2 test.py -bvv

testCommitFailure (transaction.tests.test_txn.SimpleTxnTests) ...
CRITICAL:txn:Transaction failed during second phase of two-phase commmit
Traceback (most recent call last):
  File
"/home/heimes/src/Zope3/build/lib.linux-i686-2.2/transaction/manager.py",
line 44, in _finishCommit
    r.commit(txn)
  File
"/home/heimes/src/Zope3/build/lib.linux-i686-2.2/transaction/tests/test_txn.
py", line 41, in commit
    raise RuntimeError
RuntimeError
ok

testCommitFailure (transaction.tests.test_txn.ThreadedTxnTests) ...
CRITICAL:txn:Transaction failed during second phase of two-phase commmit
Traceback (most recent call last):
  File
"/home/heimes/src/Zope3/build/lib.linux-i686-2.2/transaction/manager.py",
line 44, in _finishCommit
    r.commit(txn)
  File
"/home/heimes/src/Zope3/build/lib.linux-i686-2.2/transaction/tests/test_txn.
py", line 41, in commit
    raise RuntimeError
RuntimeError
ok


checkBadMessage1 (<class
'zodb.zeo.tests.test_conn.MappingStorageConfig:ConnectionTests'>) ...

Error in test checkBadMessage1 (<class
'zodb.zeo.tests.test_conn.MappingStorageConfig:ConnectionTests'>)
Traceback (most recent call last):
  File
"/home/heimes/src/Zope3/build/lib.linux-i686-2.2/zodb/zeo/tests/connection.p
y", line 66, in setUp
    self.startServer()
  File
"/home/heimes/src/Zope3/build/lib.linux-i686-2.2/zodb/zeo/tests/connection.p
y", line 129, in startServer
    self.monitor, self.keep, self.invq, self.timeout)
  File
"/home/heimes/src/Zope3/build/lib.linux-i686-2.2/zodb/zeo/tests/forker.py",
line 102, in start_zeo_server
    s.connect(adminaddr)
error: (111, 'Connection refused')

and lot of more error messages complaining about 'Connection refused'.

I'm using python-2.2.2 (self compiled) under Debian GNU/Linux, distribution
testing (sarge) with some packages from unstable (sid).

heimes@nathan:/home/heimes/src/Zope3$ /usr/local/bin/python2.2
Python 2.2.2 (#4, Feb  2 2003, 22:31:21)
[GCC 2.95.4 20011002 (Debian prerelease)] on linux2
Type "help", "copyright", "credits" or "license" for more information.
>>>

heimes@nathan:/home/heimes/src/Zope3$ uname -a
Linux nathan 2.4.20 #1 Tue Feb 18 00:55:38 CET 2003 i686 Pentium III
(Coppermine) GenuineIntel GNU/Linux

heimes@nathan:/home/heimes/src/Zope3$ cat /proc/version
Linux version 2.4.20 (root@nathan) (gcc version 2.95.4 20011002 (Debian
prerelease)) #1 Tue Feb 18 00:55:38 CET 2003

I dumped the connection attemps with tcpdump, perhaps this could help you to
fix the error:
nathan:/home/heimes# tcpdump -i lo not port x11-1 and not port zope
tcpdump: listening on lo
[...]
16:27:03.186597 localhost.42638 > localhost.28017: S
2046339371:2046339371(0) win 32767 <mss 16396,sackOK,timestamp 2662705
0,nop,wscale 0> (DF)
16:27:03.186631 localhost.28017 > localhost.42638: R 0:0(0) ack 2046339372
win 0 (DF)
16:27:03.436543 localhost.42639 > localhost.28017: S
2033378216:2033378216(0) win 32767 <mss 16396,sackOK,timestamp 2662730
0,nop,wscale 0> (DF)
16:27:03.436579 localhost.28017 > localhost.42639: R 0:0(0) ack 2033378217
win 0 (DF)
16:27:03.686617 localhost.42640 > localhost.28017: S
2033715291:2033715291(0) win 32767 <mss 16396,sackOK,timestamp 2662755
0,nop,wscale 0> (DF)
16:27:03.686652 localhost.28017 > localhost.42640: R 0:0(0) ack 2033715292
win 0 (DF)
16:27:03.936495 localhost.42641 > localhost.28017: S
2034698268:2034698268(0) win 32767 <mss 16396,sackOK,timestamp 2662780
0,nop,wscale 0> (DF)
16:27:03.936529 localhost.28017 > localhost.42641: R 0:0(0) ack 2034698269
win 0 (DF)
16:27:04.186574 localhost.42642 > localhost.28017: S
2034987071:2034987071(0) win 32767 <mss 16396,sackOK,timestamp 2662805
0,nop,wscale 0> (DF)
16:27:04.186610 localhost.28017 > localhost.42642: R 0:0(0) ack 2034987072
win 0 (DF)
16:27:04.436550 localhost.42643 > localhost.29209: S
2038098339:2038098339(0) win 32767 <mss 16396,sackOK,timestamp 2662830
0,nop,wscale 0> (DF)
16:27:04.436587 localhost.29209 > localhost.42643: R 0:0(0) ack 2038098340
win 0 (DF)
16:27:04.686519 localhost.42644 > localhost.29209: S
2040351385:2040351385(0) win 32767 <mss 16396,sackOK,timestamp 2662855
0,nop,wscale 0> (DF)
16:27:04.686560 localhost.29209 > localhost.42644: R 0:0(0) ack 2040351386
win 0 (DF)
16:27:04.936461 localhost.42645 > localhost.29209: S
2046315727:2046315727(0) win 32767 <mss 16396,sackOK,timestamp 2662880
0,nop,wscale 0> (DF)
16:27:04.936499 localhost.29209 > localhost.42645: R 0:0(0) ack 2046315728
win 0 (DF)
16:27:05.186589 localhost.42646 > localhost.29209: S
2039502556:2039502556(0) win 32767 <mss 16396,sackOK,timestamp 2662905
0,nop,wscale 0> (DF)
16:27:05.186627 localhost.29209 > localhost.42646: R 0:0(0) ack 2039502557
win 0 (DF)
16:27:05.436549 localhost.42647 > localhost.29209: S
2036470082:2036470082(0) win 32767 <mss 16396,sackOK,timestamp 2662930
0,nop,wscale 0> (DF)
16:27:05.436587 localhost.29209 > localhost.42647: R 0:0(0) ack 2036470083
win 0 (DF)
16:27:05.686553 localhost.42648 > localhost.29209: S
2049451442:2049451442(0) win 32767 <mss 16396,sackOK,timestamp 2662955
0,nop,wscale 0> (DF)
16:27:05.686592 localhost.29209 > localhost.42648: R 0:0(0) ack 2049451443
win 0 (DF)
16:27:05.936567 localhost.42649 > localhost.29209: S
2035343441:2035343441(0) win 32767 <mss 16396,sackOK,timestamp 2662980
0,nop,wscale 0> (DF)
16:27:05.936604 localhost.29209 > localhost.42649: R 0:0(0) ack 2035343442
win 0 (DF)
[break]

24 packets received by filter
0 packets dropped by kernel