https://bz.apache.org/bugzilla/show_bug.cgi?id=63765
--- Comment #3 from Rainer Jung <rainer.j...@kippdata.de> ---
I can also reproduce on SLES 12 and RHEL 7 but not on Solaris.
For me it does not happen with a real remote connection, but always with local
ones and with connections from another virtual guest on the same host system,
so specifics of the IP stack might be relevant.
I am using tcnative 1.2.23 build against APR 1.7.0 and OpenSSL 1.1.1d.
My curl is 7.66.0 also build against OpenSSL 1.1.1d.
Java is 1.8.0, also observable with Java 11.
TC is TC9 git 6a173978c5865421192578da21ec8f418e5a3da4 (2019-09-19).
Some debug log lines:
End of startup:
24-Sep-2019 13:48:29.200 INFO [main] org.apache.catalina.startup.Catalina.start
Server startup in [2,003] milliseconds
Start of request:
24-Sep-2019 13:49:09.410 FINE [https-openssl-nio2-8443-exec-3]
org.apache.tomcat.util.threads.LimitLatch.countUpOrAwait Counting
up[https-openssl-nio2-8443-exec-3] latch=1
24-Sep-2019 13:49:09.436 FINE [https-openssl-nio2-8443-exec-2]
org.apache.tomcat.util.net.SecureNio2Channel.processSNI The SNI host name
extracted for connection
[sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected
local=/0:0:0:0:0:0:0:1:8443 remote=/0:0:0:0:0:0:0:1:50367]] was [localhost]
24-Sep-2019 13:49:09.457 FINE [https-openssl-nio2-8443-exec-4]
org.apache.coyote.AbstractProtocol$ConnectionHandler.process Processing socket
[org.apache.tomcat.util.net.SecureNio2Channel@76110ba6:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected
local=/0:0:0:0:0:0:0:1:8443 remote=/0:0:0:0:0:0:0:1:50367]] with status
[OPEN_READ]
24-Sep-2019 13:49:09.457 FINE [https-openssl-nio2-8443-exec-4]
org.apache.coyote.AbstractProtocol$ConnectionHandler.process Found processor
[null] for socket
[org.apache.tomcat.util.net.SecureNio2Channel@76110ba6:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected
local=/0:0:0:0:0:0:0:1:8443 remote=/0:0:0:0:0:0:0:1:50367]]
24-Sep-2019 13:49:09.458 FINE [https-openssl-nio2-8443-exec-4]
org.apache.coyote.AbstractProtocol$ConnectionHandler.process Popped processor
[null] from cache
24-Sep-2019 13:49:09.493 FINE [https-openssl-nio2-8443-exec-4]
org.apache.coyote.AbstractProtocol$ConnectionHandler.register Register
Catalina:type=RequestProcessor,worker="https-openssl-nio2-8443",name=HttpRequest1
24-Sep-2019 13:49:09.497 FINE [https-openssl-nio2-8443-exec-4]
org.apache.tomcat.util.net.SocketWrapperBase.populateReadBuffer Socket:
[org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@4ed079d9:org.apache.tomcat.util.net.SecureNio2Channel@76110ba6:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected
local=/0:0:0:0:0:0:0:1:8443 remote=/0:0:0:0:0:0:0:1:50367]], Read from buffer:
[0]
24-Sep-2019 13:49:09.499 FINE [https-openssl-nio2-8443-exec-4]
org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.read Socket:
[org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@4ed079d9:org.apache.tomcat.util.net.SecureNio2Channel@76110ba6:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected
local=/0:0:0:0:0:0:0:1:8443 remote=/0:0:0:0:0:0:0:1:50367]], Read into buffer:
[0]
24-Sep-2019 13:49:09.499 FINE [https-openssl-nio2-8443-exec-4]
org.apache.coyote.AbstractProcessorLight.process Socket:
[org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@4ed079d9:org.apache.tomcat.util.net.SecureNio2Channel@76110ba6:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected
local=/0:0:0:0:0:0:0:1:8443 remote=/0:0:0:0:0:0:0:1:50367]], Status in:
[OPEN_READ], State out: [OPEN]
24-Sep-2019 13:49:09.500 FINE [https-openssl-nio2-8443-exec-4]
org.apache.coyote.AbstractProtocol$ConnectionHandler.release Pushed Processor
[org.apache.coyote.http11.Http11Processor@20d73a43]
then the hang occurs. After a minute, curl gets "empty reply from server" and
the logs show:
24-Sep-2019 13:50:09.501 FINE [https-openssl-nio2-8443-exec-6]
org.apache.coyote.AbstractProtocol$ConnectionHandler.process Processing socket
[org.apache.tomcat.util.net.SecureNio2Channel@76110ba6:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected
local=/0:0:0:0:0:0:0:1:8443 remote=/0:0:0:0:0:0:0:1:50367]] with status [ERROR]
24-Sep-2019 13:50:09.502 FINE [https-openssl-nio2-8443-exec-6]
org.apache.coyote.AbstractProtocol$ConnectionHandler.process Found processor
[null] for socket
[org.apache.tomcat.util.net.SecureNio2Channel@76110ba6:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected
local=/0:0:0:0:0:0:0:1:8443 remote=/0:0:0:0:0:0:0:1:50367]]
24-Sep-2019 13:50:09.502 FINE [https-openssl-nio2-8443-exec-6]
org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.doClose Calling
[org.apache.tomcat.util.net.Nio2Endpoint@6d79b7f7].closeSocket([org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper@4ed079d9:org.apache.tomcat.util.net.SecureNio2Channel@76110ba6:sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected
local=/0:0:0:0:0:0:0:1:8443 remote=/0:0:0:0:0:0:0:1:50367]])
java.lang.Exception
at
org.apache.tomcat.util.net.Nio2Endpoint$Nio2SocketWrapper.doClose(Nio2Endpoint.java:915)
at
org.apache.tomcat.util.net.SocketWrapperBase.close(SocketWrapperBase.java:394)
at
org.apache.tomcat.util.net.Nio2Endpoint$SocketProcessor.doRun(Nio2Endpoint.java:1682)
at
org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at
org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
at java.lang.Thread.run(Thread.java:748)
24-Sep-2019 13:50:09.502 FINE [https-openssl-nio2-8443-exec-6]
org.apache.tomcat.util.threads.LimitLatch.countDown Counting
down[https-openssl-nio2-8443-exec-6] latch=1
Regards,
Rainer
--
You are receiving this mail because:
You are the assignee for the bug.
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@tomcat.apache.org
For additional commands, e-mail: dev-h...@tomcat.apache.org
