[stunnel-users] stunnel terminates unexpectedly on FreeBSD 5.4

Bug Report:

description:

stunnel just terminated after exactly two incoming SSL connections. No error is logged, no core dump.

affected versions: (plain ./configure)

stunnel-4.04: works fine stunnel-4.05: crashes stunnel-4.08: crashes stunnel-4.10: crashes

uname -a:

FreeBSD db.kanti-zug.ch 5.4-RELEASE-p1 FreeBSD 5.4-RELEASE-p1 #0: Thu May 26 15:36:18 CEST 2005 root@db.kanti-zug.ch:/usr/src/sys/i386/compile/db i386

log:

2005.05.26 16:28:43 LOG5[20644:1]: stunnel 4.10 on i386-unknown-freebsd5.4 UCONTEXT+POLL+IPv4+LIBWRAP with OpenSSL 0.9.7e 25 Oct 2004 2005.05.26 16:28:43 LOG7[20644:1]: RAND_status claims sufficient entropy for the PRNG 2005.05.26 16:28:43 LOG6[20644:1]: PRNG seeded successfully 2005.05.26 16:28:43 LOG7[20644:1]: Certificate: /db/server.crt 2005.05.26 16:28:43 LOG7[20644:1]: Key file: /db/server.key 2005.05.26 16:28:43 LOG6[20644:1]: file ulimit = 11095 (can be changed with 'ulimit -n') 2005.05.26 16:28:43 LOG6[20644:1]: poll() used - no FD_SETSIZE limit for file descriptors 2005.05.26 16:28:43 LOG5[20644:1]: 5417 clients allowed 2005.05.26 16:28:43 LOG7[20644:1]: FD 4 in non-blocking mode 2005.05.26 16:28:43 LOG7[20644:1]: FD 5 in non-blocking mode 2005.05.26 16:28:43 LOG7[20644:1]: FD 6 in non-blocking mode 2005.05.26 16:28:43 LOG7[20644:1]: SO_REUSEADDR option set on accept socket 2005.05.26 16:28:43 LOG7[20644:1]: postgres bound to 85.158.25.7:5430 2005.05.26 16:28:43 LOG7[20644:1]: No pid file being created 2005.05.26 16:28:43 LOG7[20644:0]: Waiting -1 second(s) for 2 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->(IN) 2005.05.26 16:28:46 LOG7[20644:1]: postgres accepted FD=7 from 212.203.73.19:55038 2005.05.26 16:28:46 LOG7[20644:1]: Creating a new context 2005.05.26 16:28:46 LOG7[20644:1]: Context 2 created 2005.05.26 16:28:46 LOG7[20644:2]: postgres started 2005.05.26 16:28:46 LOG7[20644:2]: FD 7 in non-blocking mode 2005.05.26 16:28:46 LOG5[20644:2]: postgres connected from 212.203.73.19:55038 2005.05.26 16:28:46 LOG7[20644:2]: SSL state (accept): before/accept initialization 2005.05.26 16:28:46 LOG7[20644:2]: SSL state (accept): SSLv3 read client hello A 2005.05.26 16:28:46 LOG7[20644:2]: SSL state (accept): SSLv3 write server hello A 2005.05.26 16:28:46 LOG7[20644:2]: SSL state (accept): SSLv3 write certificate A 2005.05.26 16:28:46 LOG7[20644:2]: SSL state (accept): SSLv3 write certificate request A 2005.05.26 16:28:46 LOG7[20644:2]: SSL state (accept): SSLv3 flush data 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 300 second(s) for 3 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (IN)->(IN) 2005.05.26 16:28:46 LOG7[20644:2]: SSL alert (read): warning: no certificate 2005.05.26 16:28:46 LOG7[20644:2]: SSL state (accept): SSLv3 read client key exchange A 2005.05.26 16:28:46 LOG7[20644:2]: SSL state (accept): SSLv3 read finished A 2005.05.26 16:28:46 LOG7[20644:2]: SSL state (accept): SSLv3 write change cipher spec A 2005.05.26 16:28:46 LOG7[20644:2]: SSL state (accept): SSLv3 write finished A 2005.05.26 16:28:46 LOG7[20644:2]: SSL state (accept): SSLv3 flush data 2005.05.26 16:28:46 LOG7[20644:2]: 1 items in the session cache 2005.05.26 16:28:46 LOG7[20644:2]: 0 client connects (SSL_connect()) 2005.05.26 16:28:46 LOG7[20644:2]: 0 client connects that finished 2005.05.26 16:28:46 LOG7[20644:2]: 0 client renegotiatations requested 2005.05.26 16:28:46 LOG7[20644:2]: 1 server connects (SSL_accept()) 2005.05.26 16:28:46 LOG7[20644:2]: 1 server connects that finished 2005.05.26 16:28:46 LOG7[20644:2]: 0 server renegotiatiations requested 2005.05.26 16:28:46 LOG7[20644:2]: 0 session cache hits 2005.05.26 16:28:46 LOG7[20644:2]: 1 session cache misses 2005.05.26 16:28:46 LOG7[20644:2]: 0 session cache timeouts 2005.05.26 16:28:46 LOG6[20644:2]: SSL accepted: new session negotiated 2005.05.26 16:28:46 LOG6[20644:2]: Negotiated ciphers: AES256-SHA SSLv3 Kx=RSA Au=RSA Enc=AES(256) Mac=SHA1 2005.05.26 16:28:46 LOG7[20644:2]: FD 8 in non-blocking mode 2005.05.26 16:28:46 LOG7[20644:2]: postgres connecting 127.0.0.1:5432 2005.05.26 16:28:46 LOG7[20644:2]: connect_wait: waiting 10 seconds 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 10 second(s) for 3 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (INOUT)->(OUT) 2005.05.26 16:28:46 LOG7[20644:2]: connect_wait: connected 2005.05.26 16:28:46 LOG7[20644:2]: Remote FD=8 initialized 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (IN)->(IN) 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (INOUT)->(OUT) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (IN)->(IN) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (INOUT)->(OUT) 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (IN)->(IN) 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (INOUT)->(OUT) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (IN)->(IN) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (INOUT)->(OUT) 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (IN)->(IN) 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (INOUT)->(OUT) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (IN)->(IN) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (INOUT)->(OUT) 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (IN)->(IN) 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (INOUT)->(OUT) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (IN)->(IN) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (INOUT)->(OUT) 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (IN)->(IN) 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (INOUT)->(OUT) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (IN)->(IN) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (INOUT)->(OUT) 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (IN)->(IN) 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (INOUT)->(OUT) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (IN)->(IN) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (INOUT)->(OUT) 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (IN)->(IN) 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (INOUT)->(OUT) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (IN)->(IN) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (INOUT)->(OUT) 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (IN)->(IN) 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (INOUT)->(OUT) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=8, (IN)->(IN) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (IN)->() 2005.05.26 16:28:46 LOG7[20644:2]: Socket closed on read 2005.05.26 16:28:46 LOG7[20644:2]: SSL write shutdown 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 60 second(s) for 3 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (INOUT)->(OUT) 2005.05.26 16:28:46 LOG7[20644:2]: SSL alert (write): warning: close notify 2005.05.26 16:28:46 LOG7[20644:2]: SSL_shutdown retrying 2005.05.26 16:28:46 LOG7[20644:2]: SSL doesn't need to read or write 2005.05.26 16:28:46 LOG7[20644:0]: Waiting 60 second(s) for 3 file descriptor(s) 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:46 LOG7[20644:0]: CONTEXT 2, FD=7, (IN)->(IN) 2005.05.26 16:28:46 LOG7[20644:2]: SSL alert (read): warning: close notify 2005.05.26 16:28:46 LOG7[20644:2]: SSL closed on SSL_read 2005.05.26 16:28:46 LOG7[20644:2]: Socket write shutdown 2005.05.26 16:28:46 LOG5[20644:2]: Connection closed: 550 bytes sent to SSL, 446 bytes sent to socket 2005.05.26 16:28:46 LOG7[20644:2]: postgres finished (0 left) 2005.05.26 16:28:46 LOG7[20644:2]: Context 2 closed 2005.05.26 16:28:46 LOG7[20644:0]: Waiting -1 second(s) for 2 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->(IN) 2005.05.26 16:28:48 LOG7[20644:1]: postgres accepted FD=7 from 212.203.73.19:55040 2005.05.26 16:28:48 LOG7[20644:1]: Creating a new context 2005.05.26 16:28:48 LOG7[20644:1]: Context 3 created 2005.05.26 16:28:48 LOG7[20644:3]: postgres started 2005.05.26 16:28:48 LOG7[20644:3]: FD 7 in non-blocking mode 2005.05.26 16:28:48 LOG5[20644:3]: postgres connected from 212.203.73.19:55040 2005.05.26 16:28:48 LOG7[20644:3]: SSL state (accept): before/accept initialization 2005.05.26 16:28:48 LOG7[20644:3]: SSL state (accept): SSLv3 read client hello A 2005.05.26 16:28:48 LOG7[20644:3]: SSL state (accept): SSLv3 write server hello A 2005.05.26 16:28:48 LOG7[20644:3]: SSL state (accept): SSLv3 write change cipher spec A 2005.05.26 16:28:48 LOG7[20644:3]: SSL state (accept): SSLv3 write finished A 2005.05.26 16:28:48 LOG7[20644:3]: SSL state (accept): SSLv3 flush data 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 300 second(s) for 3 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (IN)->(IN) 2005.05.26 16:28:48 LOG7[20644:3]: SSL state (accept): SSLv3 read finished A 2005.05.26 16:28:48 LOG7[20644:3]: 1 items in the session cache 2005.05.26 16:28:48 LOG7[20644:3]: 0 client connects (SSL_connect()) 2005.05.26 16:28:48 LOG7[20644:3]: 0 client connects that finished 2005.05.26 16:28:48 LOG7[20644:3]: 0 client renegotiatations requested 2005.05.26 16:28:48 LOG7[20644:3]: 2 server connects (SSL_accept()) 2005.05.26 16:28:48 LOG7[20644:3]: 2 server connects that finished 2005.05.26 16:28:48 LOG7[20644:3]: 0 server renegotiatiations requested 2005.05.26 16:28:48 LOG7[20644:3]: 1 session cache hits 2005.05.26 16:28:48 LOG7[20644:3]: 1 session cache misses 2005.05.26 16:28:48 LOG7[20644:3]: 0 session cache timeouts 2005.05.26 16:28:48 LOG6[20644:3]: SSL accepted: previous session reused 2005.05.26 16:28:48 LOG7[20644:3]: FD 8 in non-blocking mode 2005.05.26 16:28:48 LOG7[20644:3]: postgres connecting 127.0.0.1:5432 2005.05.26 16:28:48 LOG7[20644:3]: connect_wait: waiting 10 seconds 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 10 second(s) for 3 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (INOUT)->(OUT) 2005.05.26 16:28:48 LOG7[20644:3]: connect_wait: connected 2005.05.26 16:28:48 LOG7[20644:3]: Remote FD=8 initialized 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (IN)->(IN) 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (INOUT)->(OUT) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (IN)->(IN) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (INOUT)->(OUT) 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (IN)->(IN) 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (INOUT)->(OUT) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (IN)->(IN) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (INOUT)->(OUT) 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (IN)->(IN) 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (INOUT)->(OUT) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (IN)->(IN) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (INOUT)->(OUT) 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (IN)->(IN) 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (INOUT)->(OUT) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (IN)->(IN) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (INOUT)->(OUT) 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (IN)->(IN) 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (INOUT)->(OUT) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (IN)->(IN) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (INOUT)->(OUT) 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (IN)->(IN) 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (INOUT)->(OUT) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (IN)->(IN) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (INOUT)->(OUT) 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (IN)->(IN) 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (INOUT)->(OUT) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (IN)->(IN) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (INOUT)->(OUT) 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (IN)->(IN) 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (INOUT)->(OUT) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 43200 second(s) for 4 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=8, (IN)->(IN) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (IN)->() 2005.05.26 16:28:48 LOG7[20644:3]: Socket closed on read 2005.05.26 16:28:48 LOG7[20644:3]: SSL write shutdown 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 60 second(s) for 3 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (INOUT)->(OUT) 2005.05.26 16:28:48 LOG7[20644:3]: SSL alert (write): warning: close notify 2005.05.26 16:28:48 LOG7[20644:3]: SSL_shutdown retrying 2005.05.26 16:28:48 LOG7[20644:3]: SSL doesn't need to read or write 2005.05.26 16:28:48 LOG7[20644:0]: Waiting 60 second(s) for 3 file descriptor(s) 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=4, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 1, FD=6, (IN)->() 2005.05.26 16:28:48 LOG7[20644:0]: CONTEXT 3, FD=7, (IN)->(IN) 2005.05.26 16:28:48 LOG7[20644:3]: SSL alert (read): warning: close notify 2005.05.26 16:28:48 LOG7[20644:3]: SSL closed on SSL_read 2005.05.26 16:28:48 LOG7[20644:3]: Socket write shutdown 2005.05.26 16:28:48 LOG5[20644:3]: Connection closed: 550 bytes sent to SSL, 446 bytes sent to socket 2005.05.26 16:28:48 LOG7[20644:3]: postgres finished (0 left)