Hello, Apache folk. After showing this bug to gstein, iholsman, and
others in IRC, I fear I may have found a real bug. It has something
to do with SSL, but it's not clear whether this is a bug in the Neon
library, OpenSSL, mod_ssl, or httpd itself.
Client is: Redhat 9, svn 0.36.0 using neon 0.24.4 / OpenSSL 0.9.7a
Server is: apache-2.0.48 / mod_dav_svn 0.35.1 / OpenSSL 0.9.6b
The reproduction recipe:
* the subversion client connects to apache via https://
* client and server successfully exchange certificates
* client begins committing a large number of new files:
(MKACTIVITY, followed by a really long stream of PUT and
PROPPATCH requests...)
* while the commit is in progress, I run 'apachectl graceful'
* I watch the errorlog, and see that it takes about 5-10 seconds for
the graceful signal to complete, but it completes successfully.
* soon afterward, the subversion commit stops in its tracks; neon
eventually times out. (If I never run 'apachectl graceful', then
the commit finishes successfully.)
More information:
* I originally encountered this bug using neon 0.24.2; with this
version of neon, the hang happens within 10 seconds of the
graceful restart completing.
* After upgrading to neon 0.24.4, it becomes harder to trigger the
hang; at least two graceful restarts are required, and the hang
happens about a minute later.
Tracing the bug:
I set the httpd.conf LogLevel to 'debug', so I could see the maximum
amount of mod_ssl spam. And there's definitely something going on
with ssl, I just don't understand.
I've attached output to the bottom of this mail; perhaps someone
clueful can guess what's going on? (Joe Orton?)
It looks like something ssl-related on the server has "timed out" and
is trying to renegotiate the ssl session, but is failing. The server
keeps trying to read bytes in the ssl negotiation, and is getting a
premature EOF in some sort of infinite loop. Also notice that the
server is trying to renegotiate the ssl connection after a
PUT/PROPPATCH to 'revs-txns.c', but the svn client believes it has
already sent about 10 more files...?!? It almost looks like HTTP
pipelining or something (but I know neon doesn't really have that.)
Perhaps this is just a neon bug, in which case we can redirect the
discussion to the neon list. But I'd like people to take a quick look
at the stuff below first.
---------------------------------------------------------
COMMANDLINE OUTPUT
$ svn import ~/projects/1.0-svn/
https://cheese.dev37.spdev.collab.net/svn/cheese/trunk/svncode -m
"import big thing"
Authentication realm: https://cheese.dev37.spdev.collab.net:443
Client certificate filename: cert.p12
Adding /home/sussman/projects/1.0-svn/svn_private_config.h.in
Adding /home/sussman/projects/1.0-svn/configure
Adding /home/sussman/projects/1.0-svn/Makefile.in
Adding /home/sussman/projects/1.0-svn/ac-helpers
[...]
Adding /home/sussman/projects/1.0-svn/subversion/libsvn_fs/notes
Adding
/home/sussman/projects/1.0-svn/subversion/libsvn_fs/notes/structu
re
Skipped '/home/sussman/projects/1.0-svn/subversion/libsvn_fs/notes/.svn'
Adding
/home/sussman/projects/1.0-svn/subversion/libsvn_fs/notes/TODO
Adding
/home/sussman/projects/1.0-svn/subversion/libsvn_fs/notes/fs-hist
ory
Adding /home/sussman/projects/1.0-svn/subversion/libsvn_fs/fs.c
Adding
/home/sussman/projects/1.0-svn/subversion/libsvn_fs/revs-txns.c
Adding
/home/sussman/projects/1.0-svn/subversion/libsvn_fs/trail.c
Adding /home/sussman/projects/1.0-svn/subversion/libsvn_fs/fs.h
Adding
/home/sussman/projects/1.0-svn/subversion/libsvn_fs/revs-txns.h
Adding
/home/sussman/projects/1.0-svn/subversion/libsvn_fs/node-rev.c
Adding
/home/sussman/projects/1.0-svn/subversion/libsvn_fs/trail.h
Adding
/home/sussman/projects/1.0-svn/subversion/libsvn_fs/node-rev.h
Adding /home/sussman/projects/1.0-svn/subversion/libsvn_fs/err.c
Adding /home/sussman/projects/1.0-svn/subversion/libsvn_fs/dag.c
Adding /home/sussman/projects/1.0-svn/subversion/libsvn_fs/util
Adding
/home/sussman/projects/1.0-svn/subversion/libsvn_fs/util/fs_skels
.h
[...... times out.]
------------------------------------------------------------------
ACCESS LOG
10.3.0.6 - - [12/Jan/2004:15:08:59 -0800] "MKCOL
/svn/cheese/!svn/wrk/2907b236-c0d0-0310-8111-bbde369f18fb/trunk/svncode/subversion/libsvn_fs/notes
HTTP/1.1" 201 477 "-"\ "SVN/0.35.1 (dev build) neon/0.24.2"
cheese.dev37.spdev.collab.net 0
10.3.0.6 - - [12/Jan/2004:15:08:59 -0800] "PUT
/svn/cheese/!svn/wrk/2907b236-c0d0-0310-8111-bbde369f18fb/trunk/svncode/subversion/libsvn_fs/notes/structure
HTTP/1.1" 201\ 485 "-" "SVN/0.35.1 (dev build) neon/0.24.2"
cheese.dev37.spdev.collab.net 0
10.3.0.6 - - [12/Jan/2004:15:09:00 -0800] "PUT
/svn/cheese/!svn/wrk/2907b236-c0d0-0310-8111-bbde369f18fb/trunk/svncode/subversion/libsvn_fs/notes/TODO
HTTP/1.1" 201 480 \"-" "SVN/0.35.1 (dev build) neon/0.24.2"
cheese.dev37.spdev.collab.net 0
10.3.0.6 - - [12/Jan/2004:15:09:00 -0800] "PUT
/svn/cheese/!svn/wrk/2907b236-c0d0-0310-8111-bbde369f18fb/trunk/svncode/subversion/libsvn_fs/notes/fs-history
HTTP/1.1" 20\1 486 "-" "SVN/0.35.1 (dev build) neon/0.24.2"
cheese.dev37.spdev.collab.net 0
10.3.0.6 - - [12/Jan/2004:15:09:00 -0800] "PUT
/svn/cheese/!svn/wrk/2907b236-c0d0-0310-8111-bbde369f18fb/trunk/svncode/subversion/libsvn_fs/fs.c
HTTP/1.1" 201 474 "-" "S\VN/0.35.1 (dev build) neon/0.24.2"
cheese.dev37.spdev.collab.net 0
10.3.0.6 - - [12/Jan/2004:15:09:00 -0800] "PROPPATCH
/svn/cheese/!svn/wrk/2907b236-c0d0-0310-8111-bbde369f18fb/trunk/svncode/subversion/libsvn_fs/fs.c
HTTP/1.1" 207 506 \"-" "SVN/0.35.1 (dev build) neon/0.24.2"
cheese.dev37.spdev.collab.net 0
10.3.0.6 - - [12/Jan/2004:15:09:00 -0800] "PUT
/svn/cheese/!svn/wrk/2907b236-c0d0-0310-8111-bbde369f18fb/trunk/svncode/subversion/libsvn_fs/revs-txns.c
HTTP/1.1" 201 481\ "-" "SVN/0.35.1 (dev build) neon/0.24.2"
cheese.dev37.spdev.collab.net 0
10.3.0.6 - - [12/Jan/2004:15:09:00 -0800] "PROPPATCH
/svn/cheese/!svn/wrk/2907b236-c0d0-0310-8111-bbde369f18fb/trunk/svncode/subversion/libsvn_fs/revs-txns.c
HTTP/1.1" 2\07 513 "-" "SVN/0.35.1 (dev build) neon/0.24.2"
cheese.dev37.spdev.collab.net 0
[.... that's it. Notice that the commandline claims to have added
about 10 more things beyond this point, but there's nothing in the
access log about it.]
[.... yes, I know that the log says neon 0.24.2, but I've since
upgraded to 0.24.4, and get the same results.]
----------------------------------------------------------------------------
ERROR LOG
[...the log entries right before this talk about successfully
authorizing a PROPPATCH on 'revs-txns.c'...]
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1499): OpenSSL: read
5/5 bytes from BIO#818c620 [mem: 823d150] (BIO dump follows)
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1446):
+-------------------------------------------------------------------------+
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1471): | 0000: 17 03
01 01 50 ....P |
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1477):
+-------------------------------------------------------------------------+
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1499): OpenSSL: read
336/336 bytes from BIO#818c620 [mem: 823d155] (BIO dump follows)
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1446):
+-------------------------------------------------------------------------+
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1471): | 0000: 44 70
0f 04 44 85 8b 45-71 d1 f2 e2 e1 bd f7 88 Dp..D..Eq....... |
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1471): | 0010: d6 21
90 52 3a 0b a7 1c-b5 60 92 67 51 13 6c 97 .!.R:....`.gQ.l. |
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1471): | 0020: d0 c2
dc 4d 17 67 12 94-0c f6 5d 80 5b be c9 4a ...M.g....].[..J |
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1471): | 0030: 6a 01
d9 0c 1b b1 83 e3-9c ca f4 0f a7 ef 70 18 j.............p. |
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1471): | 0040: c2 16
2e 0f 1e 7b 35 f0-98 29 4d 48 05 3b 83 61 .....{5..)MH.;.a |
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1471): | 0050: ed ec
2a 0a 28 ce dc 78-77 12 26 ec 4d fb 45 e3 ..*.(..xw.&.M.E. |
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1471): | 0060: b9 5e
b3 f0 67 7c ec 38-68 e6 98 d5 e5 20 66 b4 .^..g|.8h.... f. |
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1471): | 0070: 42 d6
f0 44 f6 02 ac e2-e6 1d 98 91 26 d9 0d 68 B..D........&..h |
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1471): | 0080: 62 6b
b9 c9 8c 99 66 7a-dc 29 6b eb 31 1a 89 78 bk....fz.)k.1..x |
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1471): | 0090: ab e6
a7 30 c4 e5 b5 99-59 cd ca 59 76 49 87 d4 ...0....Y..YvI.. |
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1471): | 00a0: 56 03
44 e9 17 13 7f 51-24 c7 cd 97 85 ab 2d e2 V.D....Q$.....-. |
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1471): | 00b0: 33 11
b5 52 90 e6 4d ca-41 9d 6f 88 57 49 ff ad 3..R..M.A.o.WI.. |
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1471): | 00c0: cc 8c
a8 53 11 85 4d e8-69 47 ac 3a 74 8f a3 39 ...S..M.iG.:t..9 |
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1471): | 00d0: de a5
79 b0 c3 67 c1 06-e8 3d 58 f1 35 73 57 e7 ..y..g...=X.5sW. |
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1471): | 00e0: 34 da
12 1e 0b 9f c4 11-6c 4a 44 09 31 24 3c a4 4.......lJD.1$<. |
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1471): | 00f0: 7d 84
e4 38 c0 1d ba 11-f9 3e 4a 9a 9d 6e 81 fb }..8.....>J..n.. |
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1471): | 0100: 2f 1d
cc 91 6c 6f 3f 88-9b 0c 0a fe 8a b7 9e 20 /...lo?........ |
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1471): | 0110: 58 4c
af 0d 0e 8c b8 cb-4e 66 78 19 f9 f6 6d ec XL......Nfx...m. |
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1471): | 0120: 74 eb
80 57 3a 0b 9f d8-5a 5d 31 cc cf 91 f8 25 t..W:...Z]1....% |
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1471): | 0130: bc 03
3e ad 08 01 d6 c2-d8 54 aa c1 a2 be 81 8e ..>......T...... |
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1471): | 0140: 91 e4
79 5d 2a 5e b4 57-7c 31 c5 be 4c 06 4a ef ..y]*^.W|1..L.J. |
[Mon Jan 12 15:09:00 2004] [debug] ssl_engine_io.c(1477):
+-------------------------------------------------------------------------+
[Mon Jan 12 15:09:00 2004] [debug] mpm_common.c(521): (70007)The timeout
specified has expired: connect to listener
[Mon Jan 12 15:09:00 2004] [info] mod_unique_id: using ip addr
10.1.8.137
[Mon Jan 12 15:09:01 2004] [info] Init: Initializing OpenSSL library
[Mon Jan 12 15:09:01 2004] [info] Init: Seeding PRNG with 136 bytes of
entropy
[Mon Jan 12 15:09:01 2004] [info] Loading certificate & private key of
SSL-aware server
[Mon Jan 12 15:09:01 2004] [debug] ssl_engine_pphrase.c(497):
unencrypted RSA private key - pass phrase not required
[Mon Jan 12 15:09:01 2004] [info] Init: Generating temporary RSA private
keys (512/1024 bits)
[Mon Jan 12 15:09:02 2004] [info] Init: Generating temporary DH
parameters (512/1024 bits)
[Mon Jan 12 15:09:02 2004] [info] Init: Initializing (virtual) servers
for SSL
[Mon Jan 12 15:09:02 2004] [info] Configuring server for SSL protocol
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_init.c(436): Creating new
SSL context (protocols: SSLv2, SSLv3, TLSv1)
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_init.c(553): Configuring
client authentication
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_init.c(553): Configuring
client authentication
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_init.c(1104): CA
certificate: /C=US/ST=California/L=Brisbane/O=CollabNet,
Inc./OU=Engineering/CN=CollabNet Test Certificate\ Authority - FOR
TESTING ONLY/[EMAIL PROTECTED]
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_init.c(1104): CA
certificate: /C=US/ST=California/L=Brisbane/O=CollabNet,
Inc./OU=Engineering/CN=CollabNet Test Certificate\ Authority - FOR
TESTING ONLY/[EMAIL PROTECTED]
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_init.c(636): Configuring
certificate revocation facility
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_init.c(739): Configuring
RSA server certificate
[Mon Jan 12 15:09:02 2004] [warn] RSA server certificate wildcard
CommonName (CN) `*.dev37.spdev.collab.net' does NOT match server name!?
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_init.c(778): Configuring
RSA server private key
[Mon Jan 12 15:09:02 2004] [info] Server: Apache/2.0.48, Interface:
mod_ssl/2.0.48, Library: OpenSSL/0.9.6b
[Mon Jan 12 15:09:02 2004] [notice] Apache/2.0.48 (Unix) mod_ssl/2.0.48
OpenSSL/0.9.6b DAV/2 mod_auth_mda/2.0 mod_jk/1.2.0 SVN/0.35.1
mod_auth_svn/0.1 AuthMySQL/2.20 con\figured -- resuming normal
operations
[Mon Jan 12 15:09:02 2004] [info] Server built: Jan 7 2004 14:03:58
[Mon Jan 12 15:09:02 2004] [debug] prefork.c(1037): AcceptMutex: sysvsem
(default: sysvsem)
[Mon Jan 12 15:09:02 2004] [warn] long lost child came home! (pid 17562)
[Mon Jan 12 15:09:02 2004] [warn] long lost child came home! (pid 17561)
[Mon Jan 12 15:09:02 2004] [info] Connection to child 5 established
(server dev37.spdev.collab.net:443, client 127.0.0.1)
[Mon Jan 12 15:09:02 2004] [info] Seeding PRNG with 136 bytes of entropy
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_kernel.c(1778): OpenSSL:
Handshake: start
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_kernel.c(1786): OpenSSL:
Loop: before/accept initialization
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_io.c(1510): OpenSSL: I/O
error, 11 bytes expected to read on BIO#818a5b0 [mem: 823f158]
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_kernel.c(1815): OpenSSL:
Exit: error in SSLv2/v3 read client hello A
[Mon Jan 12 15:09:02 2004] [info] (70014)End of file found: SSL
handshake interrupted by system [Hint: Stop button pressed in browser?!]
[Mon Jan 12 15:09:02 2004] [info] Connection to child 5 closed with
abortive shutdown(server dev37.spdev.collab.net:443, client 127.0.0.1)
[Mon Jan 12 15:09:02 2004] [info] Connection to child 5 established
(server dev37.spdev.collab.net:443, client 127.0.0.1)
[Mon Jan 12 15:09:02 2004] [info] Seeding PRNG with 136 bytes of entropy
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_kernel.c(1778): OpenSSL:
Handshake: start
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_kernel.c(1778): OpenSSL:
Handshake: start
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_kernel.c(1786): OpenSSL:
Loop: before/accept initialization
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_io.c(1510): OpenSSL: I/O
error, 11 bytes expected to read on BIO#818a550 [mem: 823f158]
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_kernel.c(1815): OpenSSL:
Exit: error in SSLv2/v3 read client hello A
[Mon Jan 12 15:09:02 2004] [info] (70014)End of file found: SSL
handshake interrupted by system [Hint: Stop button pressed in browser?!]
[Mon Jan 12 15:09:02 2004] [info] Connection to child 5 closed with
abortive shutdown(server dev37.spdev.collab.net:443, client 127.0.0.1)
[Mon Jan 12 15:09:02 2004] [info] Connection to child 5 established
(server dev37.spdev.collab.net:443, client 127.0.0.1)
[Mon Jan 12 15:09:02 2004] [info] Seeding PRNG with 136 bytes of entropy
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_kernel.c(1778): OpenSSL:
Handshake: start
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_kernel.c(1786): OpenSSL:
Loop: before/accept initialization
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_io.c(1510): OpenSSL: I/O
error, 11 bytes expected to read on BIO#818a5b0 [mem: 823f158]
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_kernel.c(1815): OpenSSL:
Exit: error in SSLv2/v3 read client hello A
[Mon Jan 12 15:09:02 2004] [info] (70014)End of file found: SSL
handshake interrupted by system [Hint: Stop button pressed in browser?!]
[Mon Jan 12 15:09:02 2004] [info] Connection to child 5 closed with
abortive shutdown(server dev37.spdev.collab.net:443, client 127.0.0.1)
[Mon Jan 12 15:09:02 2004] [info] Connection to child 5 established
(server dev37.spdev.collab.net:443, client 127.0.0.1)
[Mon Jan 12 15:09:02 2004] [info] Seeding PRNG with 136 bytes of entropy
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_kernel.c(1778): OpenSSL:
Handshake: start
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_kernel.c(1786): OpenSSL:
Loop: before/accept initialization
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_io.c(1510): OpenSSL: I/O
error, 11 bytes expected to read on BIO#818a550 [mem: 823f158]
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_kernel.c(1815): OpenSSL:
Exit: error in SSLv2/v3 read client hello A
[Mon Jan 12 15:09:02 2004] [info] (70014)End of file found: SSL
handshake interrupted by system [Hint: Stop button pressed in browser?!]
[Mon Jan 12 15:09:02 2004] [info] Connection to child 5 closed with
abortive shutdown(server dev37.spdev.collab.net:443, client 127.0.0.1)
[Mon Jan 12 15:09:02 2004] [info] Connection to child 5 established
(server dev37.spdev.collab.net:443, client 127.0.0.1)
[Mon Jan 12 15:09:02 2004] [info] Seeding PRNG with 136 bytes of entropy
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_kernel.c(1778): OpenSSL:
Handshake: start
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_kernel.c(1786): OpenSSL:
Loop: before/accept initialization
[Mon Jan 12 15:09:02 2004] [info] Connection to child 4 established
(server dev37.spdev.collab.net:443, client 127.0.0.1)
[Mon Jan 12 15:09:02 2004] [info] Seeding PRNG with 136 bytes of entropy
[Mon Jan 12 15:09:02 2004] [info] Seeding PRNG with 136 bytes of entropy
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_kernel.c(1778): OpenSSL:
Handshake: start
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_kernel.c(1786): OpenSSL:
Loop: before/accept initialization
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_io.c(1510): OpenSSL: I/O
error, 11 bytes expected to read on BIO#818a5b0 [mem: 823d150]
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_kernel.c(1815): OpenSSL:
Exit: error in SSLv2/v3 read client hello A
[Mon Jan 12 15:09:02 2004] [info] (70014)End of file found: SSL
handshake interrupted by system [Hint: Stop button pressed in browser?!]
[Mon Jan 12 15:09:02 2004] [info] Connection to child 4 closed with
abortive shutdown(server dev37.spdev.collab.net:443, client 127.0.0.1)
[Mon Jan 12 15:09:02 2004] [info] Connection to child 4 established
(server dev37.spdev.collab.net:443, client 127.0.0.1)
[Mon Jan 12 15:09:02 2004] [info] Seeding PRNG with 136 bytes of entropy
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_kernel.c(1778): OpenSSL:
Handshake: start
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_kernel.c(1786): OpenSSL:
Loop: before/accept initialization
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_io.c(1510): OpenSSL: I/O
error, 11 bytes expected to read on BIO#818a550 [mem: 823d150]
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_kernel.c(1815): OpenSSL:
Exit: error in SSLv2/v3 read client hello A
[Mon Jan 12 15:09:02 2004] [info] (70014)End of file found: SSL
handshake interrupted by system [Hint: Stop button pressed in browser?!]
[Mon Jan 12 15:09:02 2004] [info] Connection to child 4 closed with
abortive shutdown(server dev37.spdev.collab.net:443, client 127.0.0.1)
[Mon Jan 12 15:09:02 2004] [info] Connection to child 4 established
(server dev37.spdev.collab.net:443, client 127.0.0.1)
[Mon Jan 12 15:09:02 2004] [info] Seeding PRNG with 136 bytes of entropy
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_kernel.c(1778): OpenSSL:
Handshake: start
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_kernel.c(1786): OpenSSL:
Loop: before/accept initialization
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_io.c(1510): OpenSSL: I/O
error, 11 bytes expected to read on BIO#818a5b0 [mem: 823d150]
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_kernel.c(1815): OpenSSL:
Exit: error in SSLv2/v3 read client hello A
[Mon Jan 12 15:09:02 2004] [info] (70014)End of file found: SSL
handshake interrupted by system [Hint: Stop button pressed in browser?!]
[Mon Jan 12 15:09:02 2004] [info] Connection to child 4 closed with
abortive shutdown(server dev37.spdev.collab.net:443, client 127.0.0.1)
[Mon Jan 12 15:09:02 2004] [info] Connection to child 4 established
(server dev37.spdev.collab.net:443, client 127.0.0.1)
[Mon Jan 12 15:09:02 2004] [info] Seeding PRNG with 136 bytes of entropy
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_kernel.c(1778): OpenSSL:
Handshake: start
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_kernel.c(1786): OpenSSL:
Loop: before/accept initialization
[Mon Jan 12 15:09:02 2004] [debug] ssl_engine_io.c(1510): OpenSSL: I/O
error, 11 bytes expected to read on BIO#818a550 [mem: 823d150]
[...]
