On 13.06.2012 05:21, Konstantin Kolinko wrote:
Hi!
I run the testsuite for Tomcat trunk with APR connector with release
candidate for Tomcat Native 1.1.24, on WinXP 32-bit.
The testsuite passes successfully, but looking closer into log files I
see many occurrences of the following warning:
12.06.2012 21:13:43 org.apache.tomcat.util.net.AbstractEndpoint
countDownConnection
WARNING: Incorrect connection count, multiple socket.close called on
the same socket.
There are ~50 tests when it fails, the first one being
org.apache.catalina.authenticator.TestFormAuthenticator
It sounds like there are cases when BZ 53173 (connection counting and
maxConnections) is not fixed yet for APR connector.
You are right, I only checked for Failures and Errors. My test logs show
the following counts for this warning:
tcn-tc-ant_test-apr.out-1.1.24.rhel5.x86_64:282
tcn-tc-ant_test-apr.out-1.1.24.rhel6.x86_64:316
tcn-tc-ant_test-apr.out-1.1.24.sles10.i586:296
tcn-tc-ant_test-apr.out-1.1.24.sles10.x86_64:286
tcn-tc-ant_test-apr.out-1.1.24.sles11.x86_64:294
tcn-tc-ant_test-apr.out-1.1.24.solaris10.sparc:161
tcn-tc-ant_test-apr.out-1.1.24.solaris8.sparc:208
So it happened quite frequently. Interesting: I also ran the test suite
for the same TC trunk revision and bio and nio and one single time on
one platform I did get the same message:
tcn-tc-ant_test-bio.out-1.1.24.sles10.x86_64:1
It was in org.apache.catalina.startup.TestTomcat:
[junit] Running org.apache.catalina.startup.TestTomcat
[junit] Jun 12, 2012 11:51:50 AM org.apache.coyote.AbstractProtocol
init
[junit] INFO: Initializing ProtocolHandler
["http-bio-127.0.0.1-auto-1"]
[junit] Jun 12, 2012 11:51:50 AM
org.apache.catalina.core.StandardService startInternal
[junit] INFO: Starting service Tomcat
[junit] Jun 12, 2012 11:51:50 AM
org.apache.catalina.core.StandardEngine startInternal
[junit] INFO: Starting Servlet Engine: Apache Tomcat/8.0.0-dev
[junit] Jun 12, 2012 11:51:51 AM org.apache.coyote.AbstractProtocol
start
[junit] INFO: Starting ProtocolHandler
["http-bio-127.0.0.1-auto-1-47162"]
[junit] Jun 12, 2012 11:51:51 AM org.apache.coyote.AbstractProtocol
pause
[junit] INFO: Pausing ProtocolHandler
["http-bio-127.0.0.1-auto-1-47162"]
[junit] Jun 12, 2012 11:51:51 AM
org.apache.catalina.core.StandardService stopInternal
[junit] INFO: Stopping service Tomcat
[junit] Jun 12, 2012 11:51:51 AM org.apache.coyote.AbstractProtocol
stop
[junit] INFO: Stopping ProtocolHandler
["http-bio-127.0.0.1-auto-1-47162"]
[junit] Jun 12, 2012 11:51:51 AM org.apache.coyote.AbstractProtocol
destroy
[junit] INFO: Destroying ProtocolHandler
["http-bio-127.0.0.1-auto-1-47162"]
[junit] Jun 12, 2012 11:51:51 AM
org.apache.tomcat.util.net.AbstractEndpoint countDownConnection
[junit] WARNING: Incorrect connection count, multiple socket.close
called on the same socket.
But of course the numbers for the APR are so much higher, that it looks
like a different problem.
Here's the list of test casses for which the problem happened together
with a count of the WARNINGs (taken from the solaris 10 run):
5 [junit] Running
org.apache.catalina.authenticator.TestFormAuthenticator
7 [junit] Running org.apache.catalina.comet.TestCometProcessor
2 [junit] Running org.apache.catalina.connector.TestConnector
2 [junit] Running org.apache.catalina.connector.TestKeepAliveCount
1 [junit] Running org.apache.catalina.connector.TestMaxConnections
3 [junit] Running org.apache.catalina.connector.TestRequest
5 [junit] Running org.apache.catalina.core.TestAsyncContextImpl
4 [junit] Running org.apache.catalina.core.TestStandardContext
1 [junit] Running org.apache.catalina.core.TestStandardWrapper
8 [junit] Running org.apache.catalina.core.TestSwallowAbortedUploads
7 [junit] Running org.apache.catalina.filters.TestRemoteIpFilter
1 [junit] Running org.apache.catalina.loader.TestVirtualContext
1 [junit] Running org.apache.catalina.mbeans.TestRegistration
2 [junit] Running org.apache.catalina.servlets.TestDefaultServlet
3 [junit] Running org.apache.catalina.startup.TestTomcat
1 [junit] Running org.apache.catalina.valves.TestErrorReportValve
6 [junit] Running org.apache.catalina.websocket.TestWebSocket
1 [junit] Running org.apache.coyote.ajp.TestAbstractAjpProcessor
6 [junit] Running org.apache.coyote.http11.TestAbstractHttp11Processor
56 [junit] Running org.apache.coyote.http11.TestInternalInputBuffer
3 [junit] Running
org.apache.coyote.http11.filters.TestChunkedInputFilter
3 [junit] Running org.apache.el.TestELInJsp
3 [junit] Running org.apache.jasper.compiler.TestCompiler
4 [junit] Running org.apache.jasper.compiler.TestGenerator
1 [junit] Running org.apache.jasper.compiler.TestJspConfig
2 [junit] Running org.apache.jasper.compiler.TestJspDocumentParser
7 [junit] Running org.apache.jasper.compiler.TestParser
1 [junit] Running
org.apache.jasper.compiler.TestParserNoStrictWhitespace
1 [junit] Running org.apache.jasper.compiler.TestScriptingVariabler
1 [junit] Running org.apache.jasper.compiler.TestValidator
1 [junit] Running org.apache.naming.resources.TestProxyDirContext
2 [junit] Running org.apache.naming.resources.TestWarDirContext
1 [junit] Running org.apache.tomcat.util.http.TestCookiesAllowEquals
1 [junit] Running org.apache.tomcat.util.http.TestCookiesAllowHttpSeps
1 [junit] Running org.apache.tomcat.util.http.TestCookiesAllowNameOnly
1 [junit] Running org.apache.tomcat.util.http.TestCookiesDisallowEquals
1 [junit] Running
org.apache.tomcat.util.http.mapper.TestMapperContextRoot
3 [junit] Running org.apache.tomcat.util.net.TestClientCert
2 [junit] Running org.apache.tomcat.util.net.TestXxxEndpoint
Regards,
Rainer
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@tomcat.apache.org
For additional commands, e-mail: dev-h...@tomcat.apache.org
