View Issue Details

IDProjectCategoryView StatusLast Update
0001850GNUnetHTTP transportpublic2011-10-31 12:00
ReporterChristian Grothoff Assigned ToChristian Grothoff  
PriorityhighSeverityminorReproducibilityalways
Status closedResolutionfixed 
Product VersionGit master 
Summary0001850: transport testcases failure in https tests on fedora buildbot
Descriptionhttp://gnunet.org:8010/builders/fedora15-x86-grothoff/builds/1728/steps/tests%20transport/logs/stdio

Oct 25 15:51:47-028851 test_transport_api_https-1191 ERROR Fail! Could not connect peers
FAIL: test_transport_api_https
Oct 25 15:53:47-083838 test_transport_api_https_nat-1234 ERROR Fail! Could not connect peers
FAIL: test_transport_api_https_nat
FAIL: test_transport_api_timeout_https
Additional InformationI need to look to see if there is something specific to the buildbot that causes the problems. In any case, the error messages given are not very enlightening...
TagsNo tags attached.
Attached Files
1850.log (57,582 bytes)

Activities

Christian Grothoff

2011-10-25 22:54

manager   ~0004775

Last edited: 2011-10-25 22:58

Oh, this looks interesting:

Oct 25 22:51:36-652548 transport-https-6640 DEBUG Client: 9153F78 connection to '1AB0' https://10.0.2.15:12110/ ended with reason 35: `SSL connect error'.

Adding full log to the bug report.

Christian Grothoff

2011-10-25 23:06

manager   ~0004776

This site

https://support.zabbix.com/browse/ZBX-727

suggests that the problem might be an SSL version mismatch.

Christian Grothoff

2011-10-25 23:16

manager   ~0004777

Here is with CURL logging:


Oct 25 23:12:44-719514 transport-testing-9999 DEBUG New HELLO for peer 1 (`8MMC') with size 316
Oct 25 23:12:44-720364 transport-https-10015 DEBUG Client: 874DFA8 - 0x874dfa8 is at send pipe head!
Oct 25 23:12:44-720511 transport-https-10015 DEBUG Client: 87566A0 - 0x87566a0 is at send pipe head!
Oct 25 23:12:44-720631 transport-https-10015 DEBUG Client: 875ED98 - 0x875ed98 is at send pipe head!
Oct 25 23:12:44-720753 transport-https-10015 DEBUG Client: 8767490 - 0x8767490 is at send pipe head!
Oct 25 23:12:44-721931 transport-https-10015 DEBUG Client: 87566A0 - About to connect() to 10.0.2.15 port 12110 (#1)
Oct 25 23:12:44-722012 transport-https-10015 DEBUG Client: 87566A0 - Trying 10.0.2.15...
Oct 25 23:12:44-722104 transport-https-10015 DEBUG Client: 87566A0 - connected
Oct 25 23:12:44-722171 transport-https-10015 DEBUG Client: 87566A0 - Connected to 10.0.2.15 (10.0.2.15) port 12110 (#1)
Oct 25 23:12:44-722220 transport-https-10015 DEBUG Client: 87566A0 - Initializing NSS with certpath: sql:/etc/pki/nssdb
Oct 25 23:12:44-723178 arm-10013 INFO Starting service `ats'
Oct 25 23:12:44-787988 ats-10028 DEBUG Received `ATS_START' message
Oct 25 23:12:44-788027 ats-10028 DEBUG Received `REQUEST_ADDRESS' message
Oct 25 23:12:44-788050 ats-10028 DEBUG Cannot suggest address for peer `U06I'
Oct 25 23:12:44-796699 transport-https-10015 DEBUG Client: 87566A0 - NSS error -5938
Oct 25 23:12:44-796728 transport-https-10015 DEBUG Client: 87566A0 - Closing connection #1
Oct 25 23:12:44-796834 transport-https-10015 DEBUG Client: 875ED98 - About to connect() to 127.0.0.1 port 12110 (#2)
Oct 25 23:12:44-796941 transport-https-10015 DEBUG Client: 875ED98 - Trying 127.0.0.1...
Oct 25 23:12:44-797018 transport-https-10015 DEBUG Client: 875ED98 - connected
Oct 25 23:12:44-797046 transport-https-10015 DEBUG Client: 875ED98 - Connected to 127.0.0.1 (127.0.0.1) port 12110 (#2)
Oct 25 23:12:44-797589 transport-https-10015 DEBUG Client: 875ED98 - NSS error -5938
Oct 25 23:12:44-797616 transport-https-10015 DEBUG Client: 875ED98 - Closing connection #2
Oct 25 23:12:44-797700 transport-https-10015 DEBUG Client: 8767490 - About to connect() to 127.0.0.1 port 12110 (#3)
Oct 25 23:12:44-797811 transport-https-10015 DEBUG Client: 8767490 - Trying 127.0.0.1...
Oct 25 23:12:44-797883 transport-https-10015 DEBUG Client: 8767490 - connected
Oct 25 23:12:44-797911 transport-https-10015 DEBUG Client: 8767490 - Connected to 127.0.0.1 (127.0.0.1) port 12110 (#3)
Oct 25 23:12:44-798318 transport-https-10015 DEBUG Client: 8767490 - NSS error -5938
Oct 25 23:12:44-798339 transport-https-10015 DEBUG Client: 8767490 - Closing connection #3
Oct 25 23:12:44-798431 transport-https-10015 DEBUG Client: 87566A0 connection to 'U06I' https://10.0.2.15:12110/ ended with reason 35: `SSL connect error'
Oct 25 23:12:44-798450 transport-https-10015 DEBUG Client: 87566A0 Deleting outbound PUT session to peer `U06I'
Oct 25 23:12:44-798464 transport-https-10015 DEBUG Client: 87566A0 - Expire cleared
Oct 25 23:12:44-798490 transport-https-10015 DEBUG Client: 874DFA8 Deleting outbound GET session to peer `U06I'
Oct 25 23:12:44-798503 transport-https-10015 DEBUG Client: 874DFA8 - Expire cleared
Oct 25 23:12:44-798519 transport-https-10015 DEBUG Client: 874DFA8 - Closing connection #0
Oct 25 23:12:44-798544 transport-ats-10015 INFO Telling ATS to destroy session 0x874d320 from peer U06I
Oct 25 23:12:44-798568 transport-https-10015 DEBUG Client: 875ED98 connection to 'U06I' https://127.0.0.1:12110/ ended with reason 35: `SSL connect error'

Christian Grothoff

2011-10-25 23:18

manager   ~0004778

https://dev.twitter.com/discussions/1549

Christian Grothoff

2011-10-25 23:22

manager   ~0004779

Found this description of the error code:

PR_END_OF_FILE_ERROR -5938 "Encountered end of file"

=> pretty useless.

Christian Grothoff

2011-10-25 23:27

manager   ~0004780

If I remove the lines:

test_transport_api_https_peer1.conf:CRYPTO_INIT = NONE:+VERS-TLS1.0:+ARCFOUR-128:+SHA1:+RSA:+COMP-NULL
test_transport_api_https_peer2.conf:CRYPTO_INIT = NONE:+VERS-TLS1.0:+ARCFOUR-128:+SHA1:+RSA:+COMP-NULL

THEN the testcase passes (effectively with CRYTPO_INIT = NORMAL from defaults.conf).

So why are those lines there in the first place!?

Christian Grothoff

2011-10-26 09:21

manager   ~0004782

Fixed in SVN 17764.

Issue History

Date Modified Username Field Change
2011-10-25 16:25 Christian Grothoff New Issue
2011-10-25 16:25 Christian Grothoff Status new => assigned
2011-10-25 16:25 Christian Grothoff Assigned To => Matthias Wachs
2011-10-25 16:28 Christian Grothoff Target Version 0.9.0pre4 => 0.9.0
2011-10-25 22:54 Christian Grothoff Reproducibility have not tried => random
2011-10-25 22:54 Christian Grothoff Note Added: 0004775
2011-10-25 22:58 Christian Grothoff File Added: 1850.log
2011-10-25 22:58 Christian Grothoff Note Edited: 0004775
2011-10-25 23:06 Christian Grothoff Note Added: 0004776
2011-10-25 23:10 Christian Grothoff Summary transport testcases failure in http tests on fedora buildbot => transport testcases failure in https tests on fedora buildbot
2011-10-25 23:11 Christian Grothoff Reproducibility random => always
2011-10-25 23:16 Christian Grothoff Note Added: 0004777
2011-10-25 23:18 Christian Grothoff Note Added: 0004778
2011-10-25 23:22 Christian Grothoff Note Added: 0004779
2011-10-25 23:27 Christian Grothoff Note Added: 0004780
2011-10-25 23:28 Christian Grothoff Assigned To Matthias Wachs => Christian Grothoff
2011-10-25 23:28 Christian Grothoff Target Version 0.9.0 => 0.9.0pre4
2011-10-26 00:12 Christian Grothoff Severity major => minor
2011-10-26 09:21 Christian Grothoff Note Added: 0004782
2011-10-26 09:21 Christian Grothoff Status assigned => resolved
2011-10-26 09:21 Christian Grothoff Fixed in Version => 0.9.0pre4
2011-10-26 09:21 Christian Grothoff Resolution open => fixed
2011-10-31 12:00 Christian Grothoff Status resolved => closed