Attachment 716539 Details for Bug 927928 – stunnel log

stunnel log

server.log (text/plain), 6.74 KB, created by Jiri Pospisil on 2013-03-26 14:20:29 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Jiri Pospisil

Created: 2013-03-26 14:20:29 UTC

Size: 6.74 KB

patch

obsolete

>2013.03.26 15:06:49 LOG7[7180:140618495588288]: Snagged 64 random bytes from /home/jpospisi/.rnd
>2013.03.26 15:06:49 LOG7[7180:140618495588288]: Wrote 1024 new random bytes to /home/jpospisi/.rnd
>2013.03.26 15:06:49 LOG7[7180:140618495588288]: RAND_status claims sufficient entropy for the PRNG
>2013.03.26 15:06:49 LOG7[7180:140618495588288]: PRNG seeded successfully
>2013.03.26 15:06:49 LOG4[7180:140618495588288]: Wrong permissions on /tmp/stunnel/server.key
>2013.03.26 15:06:49 LOG7[7180:140618495588288]: Certificate: /tmp/stunnel/server.crt
>2013.03.26 15:06:49 LOG7[7180:140618495588288]: Certificate loaded
>2013.03.26 15:06:49 LOG7[7180:140618495588288]: Key file: /tmp/stunnel/server.key
>2013.03.26 15:06:49 LOG7[7180:140618495588288]: Private key loaded
>2013.03.26 15:06:49 LOG7[7180:140618495588288]: SSL context initialized for service server
>2013.03.26 15:06:49 LOG5[7180:140618495588288]: stunnel 4.29 on x86_64-redhat-linux-gnu with OpenSSL 1.0.0-fips 29 Mar 2010
>2013.03.26 15:06:49 LOG5[7180:140618495588288]: Threading:PTHREAD SSL:ENGINE Sockets:POLL,IPv6 Auth:LIBWRAP
>2013.03.26 15:06:49 LOG6[7180:140618495588288]: file ulimit = 1024 (can be changed with 'ulimit -n')
>2013.03.26 15:06:49 LOG6[7180:140618495588288]: poll() used - no FD_SETSIZE limit for file descriptors
>2013.03.26 15:06:49 LOG5[7180:140618495588288]: 500 clients allowed
>2013.03.26 15:06:49 LOG7[7180:140618495588288]: FD 9 in non-blocking mode
>2013.03.26 15:06:49 LOG7[7180:140618495588288]: FD 10 in non-blocking mode
>2013.03.26 15:06:49 LOG7[7180:140618495588288]: FD 11 in non-blocking mode
>2013.03.26 15:06:49 LOG7[7180:140618495588288]: SO_REUSEADDR option set on accept socket
>2013.03.26 15:06:49 LOG7[7180:140618495588288]: server bound to 0.0.0.0:10001
>2013.03.26 15:06:49 LOG7[7186:140618495588288]: Created pid file /tmp/stunnel/server.pid
>2013.03.26 15:06:55 LOG7[7186:140618495588288]: server accepted FD=12 from 127.0.0.1:42350
>2013.03.26 15:06:55 LOG7[7186:140618495710976]: server started
>2013.03.26 15:06:55 LOG7[7186:140618495710976]: FD 12 in non-blocking mode
>2013.03.26 15:06:55 LOG7[7186:140618495710976]: Waiting for a libwrap process
>2013.03.26 15:06:55 LOG7[7186:140618495710976]: Acquired libwrap process #0
>2013.03.26 15:06:55 LOG3[7186:140618495710976]: Unexpected socket close (read_blocking)
>2013.03.26 15:06:55 LOG5[7186:140618495710976]: Connection reset: 0 bytes sent to SSL, 0 bytes sent to socket
>2013.03.26 15:06:55 LOG7[7186:140618495710976]: server finished (0 left)
>2013.03.26 15:07:01 LOG7[7186:140618495588288]: server accepted FD=12 from 127.0.0.1:42352
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: server started
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: FD 12 in non-blocking mode
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: Waiting for a libwrap process
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: Acquired libwrap process #1
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: Releasing libwrap process #1
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: Released libwrap process #1
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: server permitted by libwrap from 127.0.0.1:42352
>2013.03.26 15:07:01 LOG5[7186:140618495710976]: server accepted connection from 127.0.0.1:42352
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: SSL state (accept): before/accept initialization
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: SSL state (accept): SSLv3 read client hello A
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: SSL state (accept): SSLv3 write server hello A
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: SSL state (accept): SSLv3 write certificate A
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: SSL state (accept): SSLv3 write certificate request A
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: SSL state (accept): SSLv3 flush data
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: SSL state (accept): SSLv3 read client certificate A
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: SSL state (accept): SSLv3 read client key exchange A
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: SSL state (accept): SSLv3 read finished A
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: SSL state (accept): SSLv3 write change cipher spec A
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: SSL state (accept): SSLv3 write finished A
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: SSL state (accept): SSLv3 flush data
>2013.03.26 15:07:01 LOG7[7186:140618495710976]:    1 items in the session cache
>2013.03.26 15:07:01 LOG7[7186:140618495710976]:    0 client connects (SSL_connect())
>2013.03.26 15:07:01 LOG7[7186:140618495710976]:    0 client connects that finished
>2013.03.26 15:07:01 LOG7[7186:140618495710976]:    0 client renegotiations requested
>2013.03.26 15:07:01 LOG7[7186:140618495710976]:    1 server connects (SSL_accept())
>2013.03.26 15:07:01 LOG7[7186:140618495710976]:    1 server connects that finished
>2013.03.26 15:07:01 LOG7[7186:140618495710976]:    0 server renegotiations requested
>2013.03.26 15:07:01 LOG7[7186:140618495710976]:    0 session cache hits
>2013.03.26 15:07:01 LOG7[7186:140618495710976]:    0 external session cache hits
>2013.03.26 15:07:01 LOG7[7186:140618495710976]:    0 session cache misses
>2013.03.26 15:07:01 LOG7[7186:140618495710976]:    0 session cache timeouts
>2013.03.26 15:07:01 LOG6[7186:140618495710976]: SSL accepted: new session negotiated
>2013.03.26 15:07:01 LOG6[7186:140618495710976]: Negotiated ciphers: AES256-SHA SSLv3 Kx=RSA Au=RSA Enc=AES(256) Mac=SHA1
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: FD 13 in non-blocking mode
>2013.03.26 15:07:01 LOG6[7186:140618495710976]: connect_blocking: connecting 127.0.0.1:10000
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: connect_blocking: s_poll_wait 127.0.0.1:10000: waiting 10 seconds
>2013.03.26 15:07:01 LOG5[7186:140618495710976]: connect_blocking: connected 127.0.0.1:10000
>2013.03.26 15:07:01 LOG5[7186:140618495710976]: server connected remote server from 127.0.0.1:51582
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: Remote FD=13 initialized
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: Socket closed on read
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: SSL write shutdown
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: SSL alert (write): warning: close notify
>2013.03.26 15:07:01 LOG6[7186:140618495710976]: SSL_shutdown successfully sent close_notify
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: SSL alert (read): warning: close notify
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: SSL closed on SSL_read
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: Socket write shutdown
>2013.03.26 15:07:01 LOG5[7186:140618495710976]: Connection closed: 188 bytes sent to SSL, 178 bytes sent to socket
>2013.03.26 15:07:01 LOG7[7186:140618495710976]: server finished (0 left)
>2013.03.26 15:07:06 LOG5[7186:140618495588288]: Received signal 15; terminating
>2013.03.26 15:07:06 LOG7[7186:140618495588288]: removing pid file /tmp/stunnel/server.pid

Actions: View

Attachments on bug 927928: 716539