I'm seeing consistent unit test failures in TestAsyncContextImpl when
using APR:
Testcase: testDispatchErrorWithThreadSingleThenComplete took 5.171 sec
Testcase: testDispatchErrorSingleThenComplete took 2.226 sec
Testcase: testTimeoutListenerCompleteNonAsyncDispatch took 2.257 sec
FAILED
Uri: /start, Status: 200, Time: 1005 duration needed >= 2900
junit.framework.AssertionFailedError: Uri: /start, Status: 200, Time:
1005 duration needed >= 2900
at
org.apache.catalina.valves.TesterAccessLogValve.validateAccessLog(TesterAccessLogValve.java:85)
at
org.apache.catalina.core.TestAsyncContextImpl.doTestTimeout(TestAsyncContextImpl.java:523)
at
org.apache.catalina.core.TestAsyncContextImpl.testTimeoutListenerCompleteNonAsyncDispatch(TestAsyncContextImpl.java:401)
The request is indeed logged as
127.0.0.1 - - 15:23:33.224 "GET /start HTTP/1.1" 200 96
http-apr-127.0.0.1-auto-3-exec-3 1005
(I have added some info to the access log) and the whole test output
around that request looks pretty simple:
29-Jan-2014 15:23:31.220 INFO [main]
org.apache.catalina.core.AprLifecycleListener.init Loaded APR based
Apache Tomcat Native library 1.1.29 using APR version 1.5.0.
29-Jan-2014 15:23:31.221 INFO [main]
org.apache.catalina.core.AprLifecycleListener.init APR capabilities:
IPv6 [true], sendfile [true], accept filters [false], random [true].
29-Jan-2014 15:23:32.175 INFO [main]
org.apache.catalina.core.AprLifecycleListener.initializeSSL OpenSSL
successfully initialized (OpenSSL 1.0.1f 6 Jan 2014)
29-Jan-2014 15:23:32.178 INFO [main]
org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler
["http-apr-127.0.0.1-auto-3"]
29-Jan-2014 15:23:32.180 INFO [main]
org.apache.catalina.core.StandardService.startInternal Starting service
Tomcat
29-Jan-2014 15:23:32.180 INFO [main]
org.apache.catalina.core.StandardEngine.startInternal Starting Servlet
Engine: Apache Tomcat/8.0.X-rXX-dev
29-Jan-2014 15:23:32.210 INFO [main]
org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler
["http-apr-127.0.0.1-auto-3-51922"]
29-Jan-2014 15:23:33.326 INFO [main]
org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler
["http-apr-127.0.0.1-auto-3-51922"]
29-Jan-2014 15:23:33.377 INFO [main]
org.apache.catalina.core.StandardService.stopInternal Stopping service
Tomcat
29-Jan-2014 15:23:33.392 INFO [main]
org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler
["http-apr-127.0.0.1-auto-3-51922"]
29-Jan-2014 15:23:33.459 INFO [main]
org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler
["http-apr-127.0.0.1-auto-3-51922"]
so only startup and - after 1 second - shutdown messages.
After that failure, more show up, I have just looked at this first one.
Testcase: testTimeoutListenerNoCompleteAsyncDispatch took 5.049 sec
FAILED
Uri: /start, Status: 200, Time: 4006 duration needed >= 5900
junit.framework.AssertionFailedError: Uri: /start, Status: 200, Time:
4006 duration needed >= 5900
at
org.apache.catalina.valves.TesterAccessLogValve.validateAccessLog(TesterAccessLogValve.java:85)
at
org.apache.catalina.core.TestAsyncContextImpl.doTestTimeout(TestAsyncContextImpl.java:523)
at
org.apache.catalina.core.TestAsyncContextImpl.testTimeoutListenerNoCompleteAsyncDispatch(TestAsyncContextImpl.java:420)
Testcase: testDispatchErrorWithThreadMultiple took 1.453 sec
Testcase: testDispatchErrorMultiple took 1.123 sec
Testcase: testDispatchMultiple took 2.239 sec
Testcase: testBug49528 took 2.47 sec
FAILED
expected:<OK> but was:<null>
junit.framework.AssertionFailedError: expected:<OK> but was:<null>
at
org.apache.catalina.core.TestAsyncContextImpl.testBug49528(TestAsyncContextImpl.java:93)
Testcase: testBug49567 took 1.665 sec
FAILED
expected:<OK> but was:<null>
junit.framework.AssertionFailedError: expected:<OK> but was:<null>
at
org.apache.catalina.core.TestAsyncContextImpl.testBug49567(TestAsyncContextImpl.java:131)
Testcase: testBug50352 took 1.822 sec
FAILED
expected:<[Runnable]-onComplete-> but was:<[onTimeout]-onComplete->
junit.framework.AssertionFailedError: expected:<[Runnable]-onComplete->
but was:<[onTimeout]-onComplete->
at
org.apache.catalina.core.TestAsyncContextImpl.testBug50352(TestAsyncContextImpl.java:1032)
and some more.
Regards,
Rainer
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@tomcat.apache.org
For additional commands, e-mail: dev-h...@tomcat.apache.org
