[stunnel-users] Version 5.54 'make check' fails test 042_inetd

James Brown jlbrown at bordo.com.au
Mon May 20 14:17:13 CEST 2019 


> On 20 May 2019, at 6:08 pm, Peter Pentchev <roam at ringlet.net> wrote:
> 
> On Mon, May 20, 2019 at 03:32:16PM +1000, James Brown wrote:
>> Sorry, posted with incorrect subject.
>> 
>>> On 20 May 2019, at 3:29 pm, James Brown <jlbrown at bordo.com.au <mailto:jlbrown at bordo.com.au>> wrote:
>>> 
>>> Having problems installing version 5.54 on macOS X with this configure command:
>>> 
>>> ./configure --with-ssl=/usr/local/Cellar/openssl at 1.1/1.1.1b
>>> 
>>> Configure runs OK, as does make.
>>> 
>>> But ‘make check’ ends with:
>>> 
>>> $ make check
> [snip]
>>> Mon 20 May 2019 15:21:58 AEST
>>> stunnel 5.54 on x86_64-apple-darwin18.5.0 platform
>>> Compiled/running with OpenSSL 1.1.1b  26 Feb 2019
>>> Threading:PTHREAD Sockets:POLL,IPv6 TLS:ENGINE,FIPS,OCSP,PSK,SNI
>>> 
> [snip]
>>> test 042_inetd                     	failed
>>> error logs                         	logs/042_inetd.log
> 
> So what does this file contain?

Hi Peter.

042_inetd.log ends with:

...
2019.05.20 15:22:07 LOG7[main]: Created pid file /Users/jlbrown/Downloads/stunnel-5.54/tests/logs/stunnel.pid
2019.05.20 15:22:07 LOG7[cron]: Cron thread initialized
2019.05.20 15:22:07 LOG6[cron]: Executing cron jobs
2019.05.20 15:22:07 LOG6[cron]: Cron jobs completed in 0 seconds
2019.05.20 15:22:07 LOG7[cron]: Waiting 86400 seconds
2019.05.20 15:22:07 LOG7[main]: Found 1 ready file descriptor(s)
2019.05.20 15:22:07 LOG7[main]: FD=4 events=0x1 revents=0x0
2019.05.20 15:22:07 LOG7[main]: FD=8 events=0x1 revents=0x1
2019.05.20 15:22:07 LOG7[main]: Service [server] accepted (FD=3) from 127.0.0.1:52886
2019.05.20 15:22:07 LOG7[0]: Service [server] started
2019.05.20 15:22:07 LOG7[0]: Setting local socket options (FD=3)
2019.05.20 15:22:07 LOG7[0]: Option TCP_NODELAY set on local socket
2019.05.20 15:22:07 LOG5[0]: Service [server] accepted connection from 127.0.0.1:52886
2019.05.20 15:22:07 LOG6[0]: Peer certificate not required
2019.05.20 15:22:07 LOG7[0]: TLS state (accept): before SSL initialization
2019.05.20 15:22:07 LOG7[0]: TLS state (accept): before SSL initialization
2019.05.20 15:22:07 LOG7[0]: SNI: no virtual services defined
2019.05.20 15:22:07 LOG7[0]: TLS state (accept): SSLv3/TLS read client hello
2019.05.20 15:22:07 LOG7[0]: TLS state (accept): SSLv3/TLS write server hello
2019.05.20 15:22:07 LOG7[0]: TLS state (accept): SSLv3/TLS write change cipher spec
2019.05.20 15:22:07 LOG7[0]: TLS state (accept): TLSv1.3 write encrypted extensions
2019.05.20 15:22:07 LOG7[0]: TLS state (accept): SSLv3/TLS write certificate
2019.05.20 15:22:07 LOG7[0]: TLS state (accept): TLSv1.3 write server certificate verify
2019.05.20 15:22:07 LOG7[0]: TLS state (accept): SSLv3/TLS write finished
2019.05.20 15:22:07 LOG7[0]: TLS state (accept): TLSv1.3 early data
2019.05.20 15:22:07 LOG7[0]: TLS state (accept): TLSv1.3 early data
2019.05.20 15:22:07 LOG7[0]: TLS state (accept): SSLv3/TLS read finished
2019.05.20 15:22:07 LOG7[0]:      1 server accept(s) requested
2019.05.20 15:22:07 LOG7[0]:      1 server accept(s) succeeded
2019.05.20 15:22:07 LOG7[0]:      0 server renegotiation(s) requested
2019.05.20 15:22:07 LOG7[0]:      0 session reuse(s)
2019.05.20 15:22:07 LOG7[0]:      0 internal session cache item(s)
2019.05.20 15:22:07 LOG7[0]:      0 internal session cache fill-up(s)
2019.05.20 15:22:07 LOG7[0]:      0 internal session cache miss(es)
2019.05.20 15:22:07 LOG7[0]:      0 external session cache hit(s)
2019.05.20 15:22:07 LOG7[0]:      0 expired session(s) retrieved
2019.05.20 15:22:07 LOG7[0]: Generate session ticket callback
2019.05.20 15:22:07 LOG7[0]: Deallocating application specific data for session connect address
2019.05.20 15:22:07 LOG7[0]: New session callback
2019.05.20 15:22:07 LOG6[0]: No peer certificate received
2019.05.20 15:22:07 LOG6[0]: Session id: 27D62810FE6CA95AAA264F75DEB370E813B7BA81BB564D954A805492574FA6A6
2019.05.20 15:22:07 LOG7[0]: TLS state (accept): SSLv3/TLS write session ticket
2019.05.20 15:22:07 LOG7[0]: Generate session ticket callback
2019.05.20 15:22:07 LOG7[0]: Deallocating application specific data for session connect address
2019.05.20 15:22:07 LOG7[0]: New session callback
2019.05.20 15:22:07 LOG6[0]: No peer certificate received
2019.05.20 15:22:07 LOG6[0]: Session id: 2CAD498A86D07F29B27487C8B3AF66C0154ED36061BFC66DC753416530BB0468
2019.05.20 15:22:07 LOG7[0]: TLS state (accept): SSLv3/TLS write session ticket
2019.05.20 15:22:07 LOG6[0]: TLS accepted: new session negotiated
2019.05.20 15:22:07 LOG6[0]: TLSv1.3 ciphersuite: TLS_CHACHA20_POLY1305_SHA256 (256-bit encryption)
2019.05.20 15:22:07 LOG7[0]: Compression: null, expansion: null
2019.05.20 15:22:07 LOG6[0]: Local mode child started (PID=70780)
2019.05.20 15:22:07 LOG7[0]: Setting remote socket options (FD=12)
2019.05.20 15:22:07 LOG7[0]: Option TCP_NODELAY set on remote socket
2019.05.20 15:22:07 LOG7[0]: Remote descriptor (FD=12) initialized
2019.05.20 15:22:07 LOG3[0]: SSL_read: Connection reset by peer (54)
2019.05.20 15:22:07 LOG5[0]: Connection reset: 0 byte(s) sent to TLS, 0 byte(s) sent to socket
2019.05.20 15:22:07 LOG7[0]: Remote descriptor (FD=12) closed
2019.05.20 15:22:07 LOG7[0]: linger (local): Invalid argument (22)
2019.05.20 15:22:07 LOG7[0]: Local descriptor (FD=3) closed
2019.05.20 15:22:07 LOG7[0]: Service [server] finished (0 left)
2019.05.20 15:22:07 LOG7[main]: Found 1 ready file descriptor(s)
2019.05.20 15:22:07 LOG7[main]: FD=4 events=0x1 revents=0x1
2019.05.20 15:22:07 LOG7[main]: FD=8 events=0x1 revents=0x0
2019.05.20 15:22:07 LOG7[main]: Dispatching a signal from the signal pipe
2019.05.20 15:22:07 LOG7[main]: Processing SIGCHLD
2019.05.20 15:22:07 LOG7[main]: Retrieving pid statuses with waitpid()
2019.05.20 15:22:07 LOG6[main]: Child process 70780 terminated on SIGPIPE
*** inetd mode ***
2019.05.20 15:22:07 LOG7[ui]: Clients allowed=97656
2019.05.20 15:22:07 LOG5[ui]: stunnel 5.54 on x86_64-apple-darwin18.5.0 platform
2019.05.20 15:22:07 LOG5[ui]: Compiled/running with OpenSSL 1.1.1b  26 Feb 2019
2019.05.20 15:22:07 LOG5[ui]: Threading:PTHREAD Sockets:POLL,IPv6 TLS:ENGINE,FIPS,OCSP,PSK,SNI
2019.05.20 15:22:07 LOG7[ui]: errno: (*__error())
2019.05.20 15:22:07 LOG5[ui]: Reading configuration from descriptor 9
2019.05.20 15:22:07 LOG5[ui]: UTF-8 byte order mark not detected
2019.05.20 15:22:07 LOG5[ui]: FIPS mode disabled
2019.05.20 15:22:07 LOG7[ui]: Compression disabled
2019.05.20 15:22:07 LOG7[ui]: No PRNG seeding was required
2019.05.20 15:22:07 LOG6[ui]: Initializing inetd mode configuration
2019.05.20 15:22:07 LOG7[ui]: Ciphers: HIGH:!aNULL:!SSLv2:!DH:!kDHEPSK
2019.05.20 15:22:07 LOG7[ui]: TLSv1.3 ciphersuites: TLS_CHACHA20_POLY1305_SHA256:TLS_AES_256_GCM_SHA384:TLS_AES_128_GCM_SHA256
2019.05.20 15:22:07 LOG7[ui]: TLS options: 0x02100004 (+0x00000000, -0x00000000)
2019.05.20 15:22:07 LOG7[ui]: No certificate or private key specified
2019.05.20 15:22:07 LOG4[ui]: Service [inetd client] needs authentication to prevent MITM attacks
2019.05.20 15:22:07 LOG5[ui]: Configuration successful
2019.05.20 15:22:07 LOG7[0]: Service [inetd client] started
2019.05.20 15:22:07 LOG5[0]: Service [inetd client] accepted connection
2019.05.20 15:22:07 LOG6[0]: s_connect: connecting 127.0.0.1:4433
2019.05.20 15:22:07 LOG7[0]: s_connect: s_poll_wait 127.0.0.1:4433: waiting 10 seconds
2019.05.20 15:22:07 LOG5[0]: s_connect: connected 127.0.0.1:4433
2019.05.20 15:22:07 LOG5[0]: Service [inetd client] connected remote server from 127.0.0.1:52886
2019.05.20 15:22:07 LOG7[0]: Setting remote socket options (FD=3)
2019.05.20 15:22:07 LOG7[0]: Option TCP_NODELAY set on remote socket
2019.05.20 15:22:07 LOG7[0]: Remote descriptor (FD=3) initialized
2019.05.20 15:22:07 LOG6[0]: SNI: sending servername: 127.0.0.1
2019.05.20 15:22:07 LOG6[0]: Peer certificate not required
2019.05.20 15:22:07 LOG7[0]: TLS state (connect): before SSL initialization
2019.05.20 15:22:07 LOG7[0]: TLS state (connect): SSLv3/TLS write client hello
2019.05.20 15:22:07 LOG7[0]: TLS state (connect): SSLv3/TLS write client hello
2019.05.20 15:22:07 LOG7[0]: TLS state (connect): SSLv3/TLS read server hello
2019.05.20 15:22:07 LOG7[0]: TLS state (connect): TLSv1.3 read encrypted extensions
2019.05.20 15:22:07 LOG6[0]: Certificate verification disabled
2019.05.20 15:22:07 LOG6[0]: Certificate verification disabled
2019.05.20 15:22:07 LOG7[0]: TLS state (connect): SSLv3/TLS read server certificate
2019.05.20 15:22:07 LOG7[0]: TLS state (connect): TLSv1.3 read server certificate verify
2019.05.20 15:22:07 LOG7[0]: TLS state (connect): SSLv3/TLS read finished
2019.05.20 15:22:07 LOG7[0]: TLS state (connect): SSLv3/TLS write change cipher spec
2019.05.20 15:22:07 LOG7[0]: TLS state (connect): SSLv3/TLS write finished
2019.05.20 15:22:07 LOG7[0]:      1 client connect(s) requested
2019.05.20 15:22:07 LOG7[0]:      1 client connect(s) succeeded
2019.05.20 15:22:07 LOG7[0]:      0 client renegotiation(s) requested
2019.05.20 15:22:07 LOG7[0]:      0 session reuse(s)
2019.05.20 15:22:07 LOG6[0]: TLS connected: new session negotiated
2019.05.20 15:22:07 LOG6[0]: TLSv1.3 ciphersuite: TLS_CHACHA20_POLY1305_SHA256 (256-bit encryption)
2019.05.20 15:22:07 LOG7[0]: Compression: null, expansion: null
2019.05.20 15:22:07 LOG3[0]: transfer() loop executes not transferring any data
2019.05.20 15:22:07 LOG3[0]: please report the problem to Michal.Trojnara at stunnel.org
2019.05.20 15:22:07 LOG3[0]: stunnel 5.54 on x86_64-apple-darwin18.5.0 platform
2019.05.20 15:22:07 LOG3[0]: Compiled/running with OpenSSL 1.1.1b  26 Feb 2019
2019.05.20 15:22:07 LOG3[0]: Threading:PTHREAD Sockets:POLL,IPv6 TLS:ENGINE,FIPS,OCSP,PSK,SNI
2019.05.20 15:22:07 LOG7[0]: errno: (*__error())
2019.05.20 15:22:07 LOG3[0]: protocol=TLSv1.3, SSL_pending=0, SSL_has_pending=0
2019.05.20 15:22:07 LOG3[0]: sock_open_rd=Y, sock_open_wr=Y
2019.05.20 15:22:07 LOG3[0]: SSL_RECEIVED_SHUTDOWN=n, SSL_SENT_SHUTDOWN=n
2019.05.20 15:22:07 LOG3[0]: sock_can_rd=n, sock_can_wr=n
2019.05.20 15:22:07 LOG3[0]: ssl_can_rd=n, ssl_can_wr=n
2019.05.20 15:22:07 LOG3[0]: read_wants_read=Y, read_wants_write=n
2019.05.20 15:22:07 LOG3[0]: write_wants_read=n, write_wants_write=n
2019.05.20 15:22:07 LOG3[0]: shutdown_wants_read=n, shutdown_wants_write=n
2019.05.20 15:22:07 LOG3[0]: socket input buffer: 0 byte(s), TLS input buffer: 0 byte(s)
2019.05.20 15:22:07 LOG5[0]: Connection reset: 0 byte(s) sent to TLS, 0 byte(s) sent to socket
2019.05.20 15:22:07 LOG7[0]: Deallocating application specific data for session connect address
2019.05.20 15:22:07 LOG7[0]: Remote descriptor (FD=3) closed
2019.05.20 15:22:07 LOG7[0]: Service [inetd client] finished (0 left)
2019.05.20 15:22:07 LOG7[0]: Deallocating section defaults
2019.05.20 15:22:07 LOG7[main]: Found 1 ready file descriptor(s)
2019.05.20 15:22:07 LOG7[main]: FD=4 events=0x1 revents=0x1
2019.05.20 15:22:07 LOG7[main]: FD=8 events=0x1 revents=0x0
2019.05.20 15:22:07 LOG7[main]: Dispatching a signal from the signal pipe
2019.05.20 15:22:07 LOG7[main]: Processing SIGNAL_TERMINATE
2019.05.20 15:22:07 LOG5[main]: Terminated
2019.05.20 15:22:07 LOG7[main]: Leak detection table utilization: 100/997, 10.03%
2019.05.20 15:22:07 LOG7[main]: Removed pid file /Users/jlbrown/Downloads/stunnel-5.54/tests/logs/stunnel.pid
2019.05.20 15:22:07 LOG7[main]: Terminating the cron thread
2019.05.20 15:22:07 LOG5[main]: Terminating 1 service thread(s)
2019.05.20 15:22:07 LOG5[main]: Service threads terminated
2019.05.20 15:22:07 LOG7[main]: Deallocating section defaults

James.
-------------- next part --------------
A non-text attachment was scrubbed...
Name: smime.p7s
Type: application/x-pkcs7-signature
Size: 4688 bytes
Desc: not available
URL: <http://www.stunnel.org/pipermail/stunnel-users/attachments/20190520/1de33815/attachment.bin>

More information about the stunnel-users mailing list