Non recycled request
Hi, I can observer RecycleRequiredException (example below) in the catalina.out of my application. This application relies on async servlets with some async libraries, this exception can lead to strange behavior I guess like mixing user cookies. Is there some specific logs I could activate to isolate the use case leading to this error ? Or some known deprecated behaviour that would lead to this state ? May 02, 2016 6:32:10 AM org.apache.catalina.connector.CoyoteAdapter checkRecycled INFO: Encountered a non-recycled request and recycled it forcedly. org.apache.catalina.connector.CoyoteAdapter$RecycleRequiredException at org.apache.catalina.connector.CoyoteAdapter.checkRecycled(CoyoteAdapter.java:592) at org.apache.coyote.http11.AbstractHttp11Processor.recycle(AbstractHttp11Processor.java:1814) at org.apache.coyote.http11.Http11NioProtocol$Http11ConnectionHandler.release(Http11NioProtocol.java:218) at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:708) at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1760) at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.run(NioEndpoint.java:1719) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) at java.lang.Thread.run(Thread.java:745) Thanks, Thomas
org.apache.catalina.connector.CoyoteAdapter.checkRecycled Encountered a non-recycled request and recycled it forcedly
Hi, I get this error in my logs: CoyoteAdapter.checkRecycled Encountered a non-recycled request and recycled it forcedly. It happen some hours or even some days ater Tomcat ist starting. I can't reproduce this error and I don't know when it starts to appear. But if it starts, it appears more and more. And everything goes crash. After the error starts to appear, many strange things happen: - there is no Session availabe in jsp (request.getSession() returns null) - filters defined in web.xml are not used - or even on one virtual host appeared a page from another virtual host!!! Then I have to restart Tomcat and everything is all right up to it happen again (some hours, some days). I tried to use the parameter: -Dorg.apache.catalina.connector.RECYCLE_FACADES=true but with no luck. Does someone have any idea what is the reason of the problem? Thank You. Below is the stack of the error, and my Tomcat configuration. 23-May-2015 16:32:37.455 INFO [ajp-nio2-127.0.0.1-8009-exec-159] org.apache.catalina.connector.CoyoteAdapter.checkRecycled Encountered a non-recycled request and recycled it forcedly. org.apache.catalina.connector.CoyoteAdapter$RecycleRequiredException at org.apache.catalina.connector.CoyoteAdapter.checkRecycled(CoyoteAdapter.java:710) at org.apache.coyote.ajp.AbstractAjpProcessor.recycle(AbstractAjpProcessor.java:943) at org.apache.coyote.ajp.AjpNio2Processor.recycle(AjpNio2Processor.java:91) at org.apache.coyote.ajp.AbstractAjpProcessor.process(AbstractAjpProcessor.java:792) at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:668) at org.apache.tomcat.util.net.Nio2Endpoint$SocketProcessor.doRun(Nio2Endpoint.java:1073) at org.apache.tomcat.util.net.Nio2Endpoint$SocketProcessor.run(Nio2Endpoint.java:1032) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) at java.lang.Thread.run(Thread.java:745) 26-May-2015 08:05:12.890 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server version: Apache Tomcat/8.0.22 26-May-2015 08:05:12.893 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server built: Apr 29 2015 11:46:15 UTC 26-May-2015 08:05:12.894 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server number: 8.0.22.0 26-May-2015 08:05:12.894 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log OS Name: Linux 26-May-2015 08:05:12.894 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log OS Version: 3.13.0-042stab106.6 26-May-2015 08:05:12.894 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Architecture: amd64 26-May-2015 08:05:12.895 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Java Home: /moje/jdk1.8.0_45/jre 26-May-2015 08:05:12.895 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Version: 1.8.0_45-b14 26-May-2015 08:05:12.895 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Vendor: Oracle Corporation 26-May-2015 08:05:12.895 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_BASE: /moje/tomcat-base 26-May-2015 08:05:12.896 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_HOME: /moje/apache-tomcat-8.0.22 26-May-2015 08:05:12.896 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.config.file=/moje/tomcat-base/conf/logging.properties 26-May-2015 08:05:12.897 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager 26-May-2015 08:05:12.897 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -XX:+UseConcMarkSweepGC 26-May-2015 08:05:12.897 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.awt.headless=true 26-May-2015 08:05:12.897 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dfile.encoding=UTF8 26-May-2015 08:05:12.898 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dsgen.parametry=/moje/tomcat-base 26-May-2015 08:05:12.898 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Xmx1400M 26-May-2015 08:05:12.898 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dorg.apache.catalina.connector.RECYCLE_FACADES=true 26-May-2015 08:05:12.898 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command
Re: org.apache.catalina.connector.CoyoteAdapter.checkRecycled Encountered a non-recycled request and recycled it forcedly
2015-05-26 14:25 GMT+03:00 Sergiusz Brzeziński sergiusz.brzezin...@supersystem.pl: Hi, I get this error in my logs: CoyoteAdapter.checkRecycled Encountered a non-recycled request and recycled it forcedly. It happen some hours or even some days ater Tomcat ist starting. I can't reproduce this error and I don't know when it starts to appear. But if it starts, it appears more and more. And everything goes crash. After the error starts to appear, many strange things happen: - there is no Session availabe in jsp (request.getSession() returns null) - filters defined in web.xml are not used - or even on one virtual host appeared a page from another virtual host!!! Then I have to restart Tomcat and everything is all right up to it happen again (some hours, some days). I tried to use the parameter: -Dorg.apache.catalina.connector.RECYCLE_FACADES=true but with no luck. Good idea. Thanks for trying. Does someone have any idea what is the reason of the problem? Thank You. Below is the stack of the error, and my Tomcat configuration. 23-May-2015 16:32:37.455 INFO [ajp-nio2-127.0.0.1-8009-exec-159] org.apache.catalina.connector.CoyoteAdapter.checkRecycled Encountered a non-recycled request and recycled it forcedly. org.apache.catalina.connector.CoyoteAdapter$RecycleRequiredException at org.apache.catalina.connector.CoyoteAdapter.checkRecycled(CoyoteAdapter.java:710) at org.apache.coyote.ajp.AbstractAjpProcessor.recycle(AbstractAjpProcessor.java:943) at org.apache.coyote.ajp.AjpNio2Processor.recycle(AjpNio2Processor.java:91) at org.apache.coyote.ajp.AbstractAjpProcessor.process(AbstractAjpProcessor.java:792) at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:668) at org.apache.tomcat.util.net.Nio2Endpoint$SocketProcessor.doRun(Nio2Endpoint.java:1073) at org.apache.tomcat.util.net.Nio2Endpoint$SocketProcessor.run(Nio2Endpoint.java:1032) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) at java.lang.Thread.run(Thread.java:745) 26-May-2015 08:05:12.890 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server version: Apache Tomcat/8.0.22 26-May-2015 08:05:12.893 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server built: Apr 29 2015 11:46:15 UTC 26-May-2015 08:05:12.894 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server number: 8.0.22.0 26-May-2015 08:05:12.894 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log OS Name: Linux 26-May-2015 08:05:12.894 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log OS Version: 3.13.0-042stab106.6 26-May-2015 08:05:12.894 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Architecture: amd64 26-May-2015 08:05:12.895 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Java Home: /moje/jdk1.8.0_45/jre 26-May-2015 08:05:12.895 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Version: 1.8.0_45-b14 26-May-2015 08:05:12.895 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Vendor: Oracle Corporation 26-May-2015 08:05:12.895 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_BASE: /moje/tomcat-base 26-May-2015 08:05:12.896 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_HOME: /moje/apache-tomcat-8.0.22 26-May-2015 08:05:12.896 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.config.file=/moje/tomcat-base/conf/logging.properties 26-May-2015 08:05:12.897 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager 26-May-2015 08:05:12.897 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -XX:+UseConcMarkSweepGC 26-May-2015 08:05:12.897 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.awt.headless=true 26-May-2015 08:05:12.897 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dfile.encoding=UTF8 26-May-2015 08:05:12.898 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dsgen.parametry=/moje/tomcat-base 26-May-2015 08:05:12.898 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Xmx1400M 26-May-2015 08:05:12.898 INFO [main
Re: org.apache.catalina.connector.CoyoteAdapter.checkRecycled Encountered a non-recycled request and recycled it forcedly
2015-05-26 14:32 GMT+02:00 Konstantin Kolinko knst.koli...@gmail.com: The forced recycling message is not a problem per se, but an indicator of a problem. In the same way as or even on one virtual host appeared a page from another virtual host that you. It signals that a request/response object was not empty at the place where it was supposed to have already been recycled. AJP Nio2 connector... Maybe try with AJP Nio one? I guess that it is more widely used than Nio2 implementation. According to the stack trace, it's just a normal AJP ping message. It does nothing except write a pong and then recycle, so the message is expected in that case. BTW, I am still not convinced by the usefulness of this warning message, which does not indicate a problem but does (you never know :) ). Tomcat 8.0.23 has already been released (is available on download servers), but there have been no announcement yet. Anything unusual in your web applications? Like using asynchronous APIs? Rémy
Re: Catalina INFO: Encountered a non-recycled request and recycled it forcedly
On 14/01/2015 20:06, Sean Dawson wrote: I'm seeing this... Jan 14, 2015 2:56:32 PM org.apache.catalina.connector.CoyoteAdapter checkRecycled INFO: Encountered a non-recycled request and recycled it forcedly. org.apache.catalina.connector.CoyoteAdapter$RecycleRequiredException at org.apache.catalina.connector.CoyoteAdapter.checkRecycled(CoyoteAdapter.java:590) at org.apache.coyote.http11.AbstractHttp11Processor.recycle(AbstractHttp11Processor.java:1792) at org.apache.coyote.http11.Http11AprProtocol$Http11ConnectionHandler.release(Http11AprProtocol.java:245) at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:694) at org.apache.tomcat.util.net.AprEndpoint$SocketProcessor.doRun(AprEndpoint.java:2466) at org.apache.tomcat.util.net.AprEndpoint$SocketProcessor.run(AprEndpoint.java:2455) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) at java.lang.Thread.run(Thread.java:745) which looks similar to... https://issues.apache.org/bugzilla/show_bug.cgi?id=57011 The thing is, I'm using 7.0.57 (on Windows64). There are lots of edge cases in the connector code, particularly around async so I'm not entirely surprised you have found one that isn't handled correctly. Are you able to reproduce this reliably? If yes, it should be a relatively easy fix. Mark - To unsubscribe, e-mail: users-unsubscr...@tomcat.apache.org For additional commands, e-mail: users-h...@tomcat.apache.org
Re: Catalina INFO: Encountered a non-recycled request and recycled it forcedly
-BEGIN PGP SIGNED MESSAGE- Hash: SHA256 Sean, On 1/14/15 3:06 PM, Sean Dawson wrote: I'm seeing this... Jan 14, 2015 2:56:32 PM org.apache.catalina.connector.CoyoteAdapter checkRecycled INFO: Encountered a non-recycled request and recycled it forcedly. org.apache.catalina.connector.CoyoteAdapter$RecycleRequiredException at org.apache.catalina.connector.CoyoteAdapter.checkRecycled(CoyoteAdapter.java:590) at org.apache.coyote.http11.AbstractHttp11Processor.recycle(AbstractHttp11Processor.java:1792) at org.apache.coyote.http11.Http11AprProtocol$Http11ConnectionHandler.release(Http11AprProtocol.java:245) at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:694) at org.apache.tomcat.util.net.AprEndpoint$SocketProcessor.doRun(AprEndpoint.java:2466) at org.apache.tomcat.util.net.AprEndpoint$SocketProcessor.run(AprEndpoint.java:2455) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) at java.lang.Thread.run(Thread.java:745) which looks similar to... https://issues.apache.org/bugzilla/show_bug.cgi?id=57011 The thing is, I'm using 7.0.57 (on Windows64). Interesting. Are you using servlet async? - -chris -BEGIN PGP SIGNATURE- Version: GnuPG v1 Comment: GPGTools - http://gpgtools.org iQIcBAEBCAAGBQJUts7OAAoJEBzwKT+lPKRYANMP/31Sg7xmrg0t3UPeTQ2FCd80 XEjgaZNWSPZujLpLLoi9t3VRZIhAsccZCU58wzu46pyCNhA39+ypaJ/TX+ib+xxv ihs9MY9S7k8KzZgQLWeLtdwr/ZYap4hxx9khJWFgOW/OaxEHkz6XoWfEDcWzG7zr Ws2bwcNOxGyo/iJHIHOdD6+64mzR4gK2u+kXwZG9Am1X4hj+yj5QmhSlCqDOgAkm RcW/3ejEwxruBQsx3IiEXqUTxJZu+9MaRnnXGd3rDlXiVeL30lckXEbvduP2lkDq u2OOMrYCNBjTSAX4/RCz+hBGCsGUC+jstV1rBrWFaCD9pDkeeYUfic2S7SNRITqZ JtsZYNxPOc/WBJk8CQ74Vryris1/yeBIik2kFF6FSWP6lfk12/OoPlt3RH/4ljwJ Ad4P5mDK1hqW36kToMBdefIkZlLJ3+xC7hdo0cyImLh1BarHuNKMdU2UQm7/FkOf FwT0Ij7nsoV9NkaLoioAN9kgmFe58vb84/NRU3/FZlVcIIAK+OMe2NWrj/dwgym+ lgjzCYdOLeFVAIA7pYiUdbN5/WGP2N5iQlUPSBTZ179YE8d2Ve2/MiobbhGFYYDU 8XrQB89VbnEHS+9n4pVMlyXs+uWNlYDDjhy+W4GNSK8mpWaSG+wOK6dNe02l4Qyj GcUlneyHpdwzRqVV7hgs =sLQt -END PGP SIGNATURE- - To unsubscribe, e-mail: users-unsubscr...@tomcat.apache.org For additional commands, e-mail: users-h...@tomcat.apache.org
Catalina INFO: Encountered a non-recycled request and recycled it forcedly
I'm seeing this... Jan 14, 2015 2:56:32 PM org.apache.catalina.connector.CoyoteAdapter checkRecycled INFO: Encountered a non-recycled request and recycled it forcedly. org.apache.catalina.connector.CoyoteAdapter$RecycleRequiredException at org.apache.catalina.connector.CoyoteAdapter.checkRecycled(CoyoteAdapter.java:590) at org.apache.coyote.http11.AbstractHttp11Processor.recycle(AbstractHttp11Processor.java:1792) at org.apache.coyote.http11.Http11AprProtocol$Http11ConnectionHandler.release(Http11AprProtocol.java:245) at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:694) at org.apache.tomcat.util.net.AprEndpoint$SocketProcessor.doRun(AprEndpoint.java:2466) at org.apache.tomcat.util.net.AprEndpoint$SocketProcessor.run(AprEndpoint.java:2455) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) at java.lang.Thread.run(Thread.java:745) which looks similar to... https://issues.apache.org/bugzilla/show_bug.cgi?id=57011 The thing is, I'm using 7.0.57 (on Windows64).
Re: Catalina INFO: Encountered a non-recycled request and recycled it forcedly
I am - could try to simplify up a testcase and see if it always happens. Thanks. On Wed, Jan 14, 2015 at 3:40 PM, Mark Thomas ma...@apache.org wrote: On 14/01/2015 20:06, Sean Dawson wrote: I'm seeing this... Jan 14, 2015 2:56:32 PM org.apache.catalina.connector.CoyoteAdapter checkRecycled INFO: Encountered a non-recycled request and recycled it forcedly. org.apache.catalina.connector.CoyoteAdapter$RecycleRequiredException at org.apache.catalina.connector.CoyoteAdapter.checkRecycled(CoyoteAdapter.java:590) at org.apache.coyote.http11.AbstractHttp11Processor.recycle(AbstractHttp11Processor.java:1792) at org.apache.coyote.http11.Http11AprProtocol$Http11ConnectionHandler.release(Http11AprProtocol.java:245) at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:694) at org.apache.tomcat.util.net.AprEndpoint$SocketProcessor.doRun(AprEndpoint.java:2466) at org.apache.tomcat.util.net.AprEndpoint$SocketProcessor.run(AprEndpoint.java:2455) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) at java.lang.Thread.run(Thread.java:745) which looks similar to... https://issues.apache.org/bugzilla/show_bug.cgi?id=57011 The thing is, I'm using 7.0.57 (on Windows64). There are lots of edge cases in the connector code, particularly around async so I'm not entirely surprised you have found one that isn't handled correctly. Are you able to reproduce this reliably? If yes, it should be a relatively easy fix. Mark - To unsubscribe, e-mail: users-unsubscr...@tomcat.apache.org For additional commands, e-mail: users-h...@tomcat.apache.org
