It's all about the answers!

Ask a question

Can these logs tell me whether my Tomcat is crashing or being shut down?


Andrew Trobec (49712137138) | asked Feb 21 '14, 5:48 a.m.
Hello,

I am troubleshooting some catalina logs to determine whether my CLM server is crashing or whether it is being brought down intentionally by an admin or some rogue process.  Here are 4 different log extracts where the server restarts.  Can somebody help me figure out what's going on?

Extract 1

Feb 10, 2014 10:21:01 AM org.apache.catalina.startup.Catalina stopServer
SEVERE: Catalina.stop: 
java.net.ConnectException: Connection refused: connect
at java.net.PlainSocketImpl.socketConnect(Native Method)
at java.net.PlainSocketImpl.doConnect(PlainSocketImpl.java:393)
at java.net.PlainSocketImpl.connectToAddress(PlainSocketImpl.java:254)
at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:241)
at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:389)
at java.net.Socket.connect(Socket.java:556)
at java.net.Socket.connect(Socket.java:504)
at java.net.Socket.<init>(Socket.java:412)
at java.net.Socket.<init>(Socket.java:226)
at org.apache.catalina.startup.Catalina.stopServer(Catalina.java:395)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:79)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:618)
at org.apache.catalina.startup.Bootstrap.stopServer(Bootstrap.java:344)
at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:435)
Feb 10, 2014 10:21:08 AM org.apache.catalina.core.AprLifecycleListener init
INFO: The APR based Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path:<removed>
Feb 10, 2014 10:21:09 AM org.apache.coyote.http11.Http11BaseProtocol init
INFO: Initializing Coyote HTTP/1.1 on http-9080
Feb 10, 2014 10:21:10 AM org.apache.coyote.http11.Http11BaseProtocol init
INFO: Initializing Coyote HTTP/1.1 on http-9443
Feb 10, 2014 10:21:10 AM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 2564 ms
Feb 10, 2014 10:21:10 AM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
Feb 10, 2014 10:21:10 AM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/5.5.33
Feb 10, 2014 10:21:10 AM org.apache.catalina.core.StandardHost start
INFO: XML validation disabled
Feb 10, 2014 10:22:09 AM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive clmhelp.war
Feb 10, 2014 10:22:16 AM org.apache.coyote.http11.Http11BaseProtocol start
INFO: Starting Coyote HTTP/1.1 on http-9080
Feb 10, 2014 10:22:16 AM org.apache.coyote.http11.Http11BaseProtocol start
INFO: Starting Coyote HTTP/1.1 on http-9443
Feb 10, 2014 10:22:16 AM org.apache.catalina.storeconfig.StoreLoader load
INFO: Find registry server-registry.xml at classpath resource
Feb 10, 2014 10:22:16 AM org.apache.catalina.startup.Catalina start
INFO: Server startup in 66349 ms

Extract 2

Feb 11, 2014 7:08:11 PM org.apache.coyote.http11.Http11BaseProtocol pause
INFO: Pausing Coyote HTTP/1.1 on http-9080
Feb 11, 2014 7:17:59 PM org.apache.catalina.startup.Catalina stopServer
SEVERE: Catalina.stop: 
java.net.ConnectException: Connection refused: connect
at java.net.PlainSocketImpl.socketConnect(Native Method)
at java.net.PlainSocketImpl.doConnect(PlainSocketImpl.java:393)
at java.net.PlainSocketImpl.connectToAddress(PlainSocketImpl.java:254)
at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:241)
at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:389)
at java.net.Socket.connect(Socket.java:556)
at java.net.Socket.connect(Socket.java:504)
at java.net.Socket.<init>(Socket.java:412)
at java.net.Socket.<init>(Socket.java:226)
at org.apache.catalina.startup.Catalina.stopServer(Catalina.java:395)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:79)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:618)
at org.apache.catalina.startup.Bootstrap.stopServer(Bootstrap.java:344)
at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:435)
Feb 11, 2014 7:18:08 PM org.apache.catalina.core.AprLifecycleListener init
INFO: The APR based Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path:<removed>
Feb 11, 2014 7:18:08 PM org.apache.coyote.http11.Http11BaseProtocol init
INFO: Initializing Coyote HTTP/1.1 on http-9080
Feb 11, 2014 7:18:10 PM org.apache.coyote.http11.Http11BaseProtocol init
INFO: Initializing Coyote HTTP/1.1 on http-9443
Feb 11, 2014 7:18:10 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 2314 ms
Feb 11, 2014 7:18:10 PM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
Feb 11, 2014 7:18:10 PM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/5.5.33
Feb 11, 2014 7:18:10 PM org.apache.catalina.core.StandardHost start
INFO: XML validation disabled
Feb 11, 2014 7:19:23 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive clmhelp.war
Feb 11, 2014 7:19:31 PM org.apache.coyote.http11.Http11BaseProtocol start
INFO: Starting Coyote HTTP/1.1 on http-9080
Feb 11, 2014 7:19:31 PM org.apache.coyote.http11.Http11BaseProtocol start
INFO: Starting Coyote HTTP/1.1 on http-9443
Feb 11, 2014 7:19:31 PM org.apache.catalina.storeconfig.StoreLoader load
INFO: Find registry server-registry.xml at classpath resource
Feb 11, 2014 7:19:31 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 81296 ms

Extract 3

Feb 14, 2014 2:07:08 PM org.apache.coyote.http11.Http11BaseProtocol pause
INFO: Pausing Coyote HTTP/1.1 on http-9080
Feb 14, 2014 2:07:08 PM org.apache.coyote.http11.Http11BaseProtocol pause
INFO: Pausing Coyote HTTP/1.1 on http-9443
Feb 14, 2014 2:07:09 PM org.apache.catalina.core.StandardService stop
INFO: Stopping service Catalina
Feb 14, 2014 2:12:21 PM org.apache.coyote.http11.Http11BaseProtocol destroy
INFO: Stopping Coyote HTTP/1.1 on http-9080
Feb 14, 2014 2:12:21 PM org.apache.coyote.http11.Http11BaseProtocol destroy
INFO: Stopping Coyote HTTP/1.1 on http-9443
Feb 14, 2014 2:13:12 PM org.apache.catalina.startup.Catalina stopServer
SEVERE: Catalina.stop: 
java.net.ConnectException: Connection refused: connect
at java.net.PlainSocketImpl.socketConnect(Native Method)
at java.net.PlainSocketImpl.doConnect(PlainSocketImpl.java:393)
at java.net.PlainSocketImpl.connectToAddress(PlainSocketImpl.java:254)
at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:241)
at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:389)
at java.net.Socket.connect(Socket.java:556)
at java.net.Socket.connect(Socket.java:504)
at java.net.Socket.<init>(Socket.java:412)
at java.net.Socket.<init>(Socket.java:226)
at org.apache.catalina.startup.Catalina.stopServer(Catalina.java:395)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:79)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:618)
at org.apache.catalina.startup.Bootstrap.stopServer(Bootstrap.java:344)
at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:435)
Feb 14, 2014 4:32:29 PM org.apache.catalina.core.AprLifecycleListener init
INFO: The APR based Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path:<removed>
Feb 14, 2014 4:32:29 PM org.apache.coyote.http11.Http11BaseProtocol init
INFO: Initializing Coyote HTTP/1.1 on http-9080
Feb 14, 2014 4:32:30 PM org.apache.coyote.http11.Http11BaseProtocol init
INFO: Initializing Coyote HTTP/1.1 on http-9443
Feb 14, 2014 4:32:30 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 1626 ms
Feb 14, 2014 4:32:30 PM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
Feb 14, 2014 4:32:30 PM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/5.5.33
Feb 14, 2014 4:32:30 PM org.apache.catalina.core.StandardHost start
INFO: XML validation disabled
Feb 14, 2014 4:32:56 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive clmhelp.war
Feb 14, 2014 4:33:01 PM org.apache.coyote.http11.Http11BaseProtocol start
INFO: Starting Coyote HTTP/1.1 on http-9080
Feb 14, 2014 4:33:01 PM org.apache.coyote.http11.Http11BaseProtocol start
INFO: Starting Coyote HTTP/1.1 on http-9443
Feb 14, 2014 4:33:01 PM org.apache.catalina.storeconfig.StoreLoader load
INFO: Find registry server-registry.xml at classpath resource
Feb 14, 2014 4:33:01 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 30344 ms
Feb 14, 2014 4:33:22 PM org.apache.coyote.http11.Http11BaseProtocol pause
INFO: Pausing Coyote HTTP/1.1 on http-9080
Feb 14, 2014 4:33:22 PM org.apache.coyote.http11.Http11BaseProtocol pause
INFO: Pausing Coyote HTTP/1.1 on http-9443
Feb 14, 2014 4:33:23 PM org.apache.catalina.core.StandardService stop
INFO: Stopping service Catalina
Feb 14, 2014 4:33:26 PM org.apache.coyote.http11.Http11BaseProtocol destroy
INFO: Stopping Coyote HTTP/1.1 on http-9080
Feb 14, 2014 4:33:26 PM org.apache.coyote.http11.Http11BaseProtocol destroy
INFO: Stopping Coyote HTTP/1.1 on http-9443
Feb 14, 2014 4:33:35 PM org.apache.catalina.core.AprLifecycleListener init
INFO: The APR based Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path:<removed>
Feb 14, 2014 4:33:35 PM org.apache.coyote.http11.Http11BaseProtocol init
INFO: Initializing Coyote HTTP/1.1 on http-9080
Feb 14, 2014 4:33:36 PM org.apache.coyote.http11.Http11BaseProtocol init
INFO: Initializing Coyote HTTP/1.1 on http-9443
Feb 14, 2014 4:33:36 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 1579 ms
Feb 14, 2014 4:33:36 PM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
Feb 14, 2014 4:33:36 PM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/5.5.33
Feb 14, 2014 4:33:36 PM org.apache.catalina.core.StandardHost start
INFO: XML validation disabled
Feb 14, 2014 4:34:02 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive clmhelp.war
Feb 14, 2014 4:34:06 PM org.apache.coyote.http11.Http11BaseProtocol start
INFO: Starting Coyote HTTP/1.1 on http-9080
Feb 14, 2014 4:34:06 PM org.apache.coyote.http11.Http11BaseProtocol start
INFO: Starting Coyote HTTP/1.1 on http-9443
Feb 14, 2014 4:34:06 PM org.apache.catalina.storeconfig.StoreLoader load
INFO: Find registry server-registry.xml at classpath resource
Feb 14, 2014 4:34:06 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 29774 ms

Extract 4

Feb 18, 2014 10:28:51 PM org.apache.coyote.http11.Http11BaseProtocol pause
INFO: Pausing Coyote HTTP/1.1 on http-9080
Feb 18, 2014 11:38:32 PM org.apache.catalina.startup.Catalina stopServer
SEVERE: Catalina.stop: 
java.net.ConnectException: Connection refused: connect
at java.net.PlainSocketImpl.socketConnect(Native Method)
at java.net.PlainSocketImpl.doConnect(PlainSocketImpl.java:393)
at java.net.PlainSocketImpl.connectToAddress(PlainSocketImpl.java:254)
at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:241)
at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:389)
at java.net.Socket.connect(Socket.java:556)
at java.net.Socket.connect(Socket.java:504)
at java.net.Socket.<init>(Socket.java:412)
at java.net.Socket.<init>(Socket.java:226)
at org.apache.catalina.startup.Catalina.stopServer(Catalina.java:395)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:79)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:618)
at org.apache.catalina.startup.Bootstrap.stopServer(Bootstrap.java:344)
at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:435)
Feb 18, 2014 11:38:40 PM org.apache.catalina.core.AprLifecycleListener init
INFO: The APR based Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path:<removed>
Feb 18, 2014 11:38:40 PM org.apache.coyote.http11.Http11BaseProtocol init
INFO: Initializing Coyote HTTP/1.1 on http-9080
Feb 18, 2014 11:38:42 PM org.apache.coyote.http11.Http11BaseProtocol init
INFO: Initializing Coyote HTTP/1.1 on http-9443
Feb 18, 2014 11:38:42 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 2455 ms
Feb 18, 2014 11:38:42 PM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
Feb 18, 2014 11:38:42 PM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/5.5.33
Feb 18, 2014 11:38:42 PM org.apache.catalina.core.StandardHost start
INFO: XML validation disabled
Feb 18, 2014 11:40:20 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive clmhelp.war
Feb 18, 2014 11:40:29 PM org.apache.coyote.http11.Http11BaseProtocol start
INFO: Starting Coyote HTTP/1.1 on http-9080
Feb 18, 2014 11:40:29 PM org.apache.coyote.http11.Http11BaseProtocol start
INFO: Starting Coyote HTTP/1.1 on http-9443
Feb 18, 2014 11:40:29 PM org.apache.catalina.storeconfig.StoreLoader load
INFO: Find registry server-registry.xml at classpath resource
Feb 18, 2014 11:40:30 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 107714 ms

As you can see, there is sometimes a pause a few minutes before the server is stopped and restarted.  The fact that the server starts almost immediately leads me to believe that there is some sort of restart being scheduled.

I don't expect a definitive solution, but maybe somebody has had a similart occurance and point to something that might be causing the problem (scheduled tasks or similar)

Thanks!

Andrew

One answer



permanent link
Ralph Schoon (57.0k23642) | answered Feb 21 '14, 6:58 a.m.
FORUM ADMINISTRATOR / FORUM MODERATOR / JAZZ DEVELOPER
Andrew, if I request a shutdown, my catalina logs in Tomcat/logs show:
INFO: A valid shutdown command was received via the shutdown port. Stopping the Server instance.

Comments
Andrew Trobec commented Feb 21 '14, 7:09 a.m.

Hello Ralph,


Thanks for your input.  When you request a shutdown, are you doing it via windows service or the Jazz Team Server batch files?

Regards,

Andrew


Ralph Schoon commented Feb 21 '14, 7:39 a.m.
FORUM ADMINISTRATOR / FORUM MODERATOR / JAZZ DEVELOPER

As I usually run demo/development/test systems I start them with the batch files.
If you run a service, the service logs should tell you when the service was shut down.

Your answer


Register or to post your answer.