On Thu, Dec 27, 2018 at 4:35 PM 李洋 <liyang1...@gmail.com> wrote: > > https://stackoverflow.com/questions/28776520/systemd-service-for-jar-file-gets-operation-timed-out-error-after-few-minues-o > this issue is familiar your‘s，hope this answe wil help you！ > > Steve Demy <steve.d...@shaw.ca> 于2018年12月27日周四 下午12:20写道： > > > Tomcat 9.0.14 startup fails, or at least is not recognized as complete by > > Ubuntu’s systemd which times out: > > > > Dec 25 05:19:09 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:09.586 > INFO > > [main] org.apache.catalina.startup.Catalina.start Server startup in > [1,868] > > milliseconds > > Dec 25 05:20:34 vps169399 systemd[1]: tomcat.service: Start operation > > timed out. Terminating. > > > > A stop operation then fails (port 8005 held by the hung start operation?) > > and tomcat is killed by the OS. > > > > Systemd then restarts Tomcat 10 seconds later resulting in a start/stop > > loop. After a system reboot, one start operation will proceed normally, > > but any restart results in the start/stop loop. There are no webapps > > involved except the tomcat packed ones. > > > > Tomcat 9.0.13 works perfectly with identical installation and > > configuration. What is systemd is expecting that it is not getting from > > 9.0.14? Any clue will be much appreciated. > > > > The systemd script and full startup log are enclosed below, which > contains > > details of the environment. > > > > [Unit] > > Description=Apache Tomcat Web Application Container > > After=network.target > > > > [Service] > > Type=forking > > > > Environment=JAVA_HOME=/usr/lib/jvm/jdk-11.0.1 > > Environment=CATALINA_PID=/opt/tomcat/temp/tomcat.pid > > Environment=CATALINA_HOME=/opt/tomcat > > Environment=CATALINA_BASE=/opt/tomcat > > Environment='JAVA_OPTS=-Djava.awt.headless=true > > -Djava.security.egd=file:/dev/./urandom' > > Environment=LD_LIBRARY_PATH=/opt/tomcat/lib > > Environment='CATALINA_OPTS=-Xms1024m -Xmx1024m' > > > > ExecStart=/opt/tomcat/bin/startup.sh > > ExecStop=/opt/tomcat/bin/shutdown.sh > > > > User=tomcatuser > > Group=tomcatgroup > > UMask=0007 > > RestartSec=10 > > Restart=always > > > > [Install] > > WantedBy=multi-user.target > > > > > > Complete startup log: > > > > -- Unit tomcat.service has begun starting up. > > Dec 25 05:19:04 vps169399 catalina.sh[9716]: NOTE: Picked up > > JDK_JAVA_OPTIONS: --add-opens=java.base/java.lang=ALL-UNNAMED > > --add-opens=java.base/java.io=ALL-UNNAMED > > --add-opens=java.rmi/sun.rmi.transport=ALL-UNNAMED > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.362 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log Server > version > > name: Apache Tomcat/9.0.14 > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.376 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log Server > built: > > Dec 6 2018 21:13:53 UTC > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.380 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log Server > version > > number: 9.0.14.0 > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.381 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log OS Name: > > Linux > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.382 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log OS Version: > > 4.15.0-43-generic > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.383 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log > Architecture: > > amd64 > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.383 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log Java Home: > > /usr/lib/jvm/jdk-11.0.1 > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.384 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Version: > > 11.0.1+13 > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.384 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Vendor: > > Oracle Corporation > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.386 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log > > CATALINA_BASE: /opt/tomcat > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.386 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log > > CATALINA_HOME: /opt/tomcat > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.387 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log Command line > > argument: --add-opens=java.base/java.lang=ALL-UNNAMED > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.388 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log Command line > > argument: --add-opens=java.base/java.io=ALL-UNNAMED > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.390 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log Command line > > argument: --add-opens=java.rmi/sun.rmi.transport=ALL-UNNAMED > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.392 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log Command line > > argument: > > -Djava.util.logging.config.file=/opt/tomcat/conf/logging.properties > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.396 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log Command line > > argument: > -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.396 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log Command line > > argument: -Djava.awt.headless=true > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.397 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log Command line > > argument: -Djava.security.egd=file:/dev/./urandom > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.398 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log Command line > > argument: -Djdk.tls.ephemeralDHKeySize=2048 > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.399 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log Command line > > argument: -Djava.protocol.handler.pkgs=org.apache.catalina.webresources > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.399 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log Command line > > argument: -Dorg.apache.catalina.security.SecurityListener.UMASK=0027 > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.404 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log Command line > > argument: -Xms1024m > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.405 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log Command line > > argument: -Xmx1024m > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.406 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log Command line > > argument: -Dignore.endorsed.dirs= > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.407 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log Command line > > argument: -Dcatalina.base=/opt/tomcat > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.407 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log Command line > > argument: -Dcatalina.home=/opt/tomcat > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.409 > INFO > > [main] org.apache.catalina.startup.VersionLoggerListener.log Command line > > argument: -Djava.io.tmpdir=/opt/tomcat/temp > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.410 > INFO > > [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent > Loaded > > APR based Apache Tomcat Native library [1.2.19] using APR version > [1.6.5]. > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.411 > INFO > > [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR > > capabilities: IPv6 [true], sendfile [true], accept filters [false], > random > > [true]. > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.411 > INFO > > [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent > > APR/OpenSSL configuration: useAprConnector [false], useOpenSSL [true] > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.427 > INFO > > [main] org.apache.catalina.core.AprLifecycleListener.initializeSSL > OpenSSL > > successfully initialized [OpenSSL 1.1.0g 2 Nov 2017] > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.561 > INFO > > [main] org.apache.coyote.AbstractProtocol.init Initializing > ProtocolHandler > > ["http-nio-8080"] > > Dec 25 05:19:06 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:06.646 > INFO > > [main] org.apache.coyote.AbstractProtocol.init Initializing > ProtocolHandler > > ["https-jsse-nio-8443"] > > Dec 25 05:19:07 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:07.712 > INFO > > [main] org.apache.coyote.AbstractProtocol.init Initializing > ProtocolHandler > > ["ajp-nio-8009"] > > Dec 25 05:19:07 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:07.717 > INFO > > [main] org.apache.catalina.startup.Catalina.load Server initialization in > > [2,772] milliseconds > > Dec 25 05:19:07 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:07.857 > INFO > > [main] org.apache.catalina.core.StandardService.startInternal Starting > > service [Catalina] > > Dec 25 05:19:07 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:07.860 > INFO > > [main] org.apache.catalina.core.StandardEngine.startInternal Starting > > Servlet engine: [Apache Tomcat/9.0.14] > > Dec 25 05:19:07 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:07.876 > INFO > > [main] org.apache.catalina.startup.HostConfig.deployDirectory Deploying > web > > application directory [/opt/tomcat/webapps/host-manager] > > Dec 25 05:19:08 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:08.698 > INFO > > [main] org.apache.catalina.startup.HostConfig.deployDirectory Deployment > of > > web application directory [/opt/tomcat/webapps/host-manager] has finished > > in [822] ms > > Dec 25 05:19:08 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:08.700 > INFO > > [main] org.apache.catalina.startup.HostConfig.deployDirectory Deploying > web > > application directory [/opt/tomcat/webapps/examples] > > Dec 25 05:19:09 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:09.344 > INFO > > [main] org.apache.catalina.startup.HostConfig.deployDirectory Deployment > of > > web application directory [/opt/tomcat/webapps/examples] has finished in > > [644] ms > > Dec 25 05:19:09 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:09.345 > INFO > > [main] org.apache.catalina.startup.HostConfig.deployDirectory Deploying > web > > application directory [/opt/tomcat/webapps/docs] > > Dec 25 05:19:09 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:09.405 > INFO > > [main] org.apache.catalina.startup.HostConfig.deployDirectory Deployment > of > > web application directory [/opt/tomcat/webapps/docs] has finished in > [59] ms > > Dec 25 05:19:09 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:09.405 > INFO > > [main] org.apache.catalina.startup.HostConfig.deployDirectory Deploying > web > > application directory [/opt/tomcat/webapps/manager] > > Dec 25 05:19:09 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:09.467 > INFO > > [main] org.apache.catalina.startup.HostConfig.deployDirectory Deployment > of > > web application directory [/opt/tomcat/webapps/manager] has finished in > > [61] ms > > Dec 25 05:19:09 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:09.470 > INFO > > [main] org.apache.catalina.startup.HostConfig.deployDirectory Deploying > web > > application directory [/opt/tomcat/webapps/ROOT] > > Dec 25 05:19:09 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:09.523 > INFO > > [main] org.apache.catalina.startup.HostConfig.deployDirectory Deployment > of > > web application directory [/opt/tomcat/webapps/ROOT] has finished in > [52] ms > > Dec 25 05:19:09 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:09.534 > INFO > > [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler > > ["http-nio-8080"] > > Dec 25 05:19:09 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:09.572 > INFO > > [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler > > ["https-jsse-nio-8443"] > > Dec 25 05:19:09 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:09.582 > INFO > > [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler > > ["ajp-nio-8009"] > > Dec 25 05:19:09 vps169399 catalina.sh[9716]: 25-Dec-2018 05:19:09.586 > INFO > > [main] org.apache.catalina.startup.Catalina.start Server startup in > [1,868] > > milliseconds > > Dec 25 05:20:34 vps169399 systemd[1]: tomcat.service: Start operation > > timed out. Terminating. > > Dec 25 05:20:34 vps169399 catalina.sh[9716]: 25-Dec-2018 05:20:34.531 > INFO > > [Thread-5] org.apache.coyote.AbstractProtocol.pause Pausing > ProtocolHandler > > ["http-nio-8080"] > > Dec 25 05:20:34 vps169399 catalina.sh[9716]: 25-Dec-2018 05:20:34.550 > INFO > > [Thread-5] org.apache.coyote.AbstractProtocol.pause Pausing > ProtocolHandler > > ["https-jsse-nio-8443"] > > Dec 25 05:20:34 vps169399 catalina.sh[9716]: 25-Dec-2018 05:20:34.552 > INFO > > [Thread-5] org.apache.coyote.AbstractProtocol.pause Pausing > ProtocolHandler > > ["ajp-nio-8009"] > > Dec 25 05:20:34 vps169399 catalina.sh[9716]: 25-Dec-2018 05:20:34.553 > INFO > > [Thread-5] org.apache.catalina.core.StandardService.stopInternal Stopping > > service [Catalina] > > Dec 25 05:20:34 vps169399 catalina.sh[9716]: 25-Dec-2018 05:20:34.675 > INFO > > [Thread-5] org.apache.coyote.AbstractProtocol.stop Stopping > ProtocolHandler > > ["http-nio-8080"] > > Dec 25 05:20:34 vps169399 catalina.sh[9716]: 25-Dec-2018 05:20:34.686 > INFO > > [Thread-5] org.apache.coyote.AbstractProtocol.stop Stopping > ProtocolHandler > > ["https-jsse-nio-8443"] > > Dec 25 05:20:34 vps169399 catalina.sh[9716]: 25-Dec-2018 05:20:34.689 > INFO > > [Thread-5] org.apache.coyote.AbstractProtocol.stop Stopping > ProtocolHandler > > ["ajp-nio-8009"] > > Dec 25 05:20:34 vps169399 catalina.sh[9716]: 25-Dec-2018 05:20:34.693 > INFO > > [Thread-5] org.apache.coyote.AbstractProtocol.destroy Destroying > > ProtocolHandler ["http-nio-8080"] > > Dec 25 05:20:34 vps169399 catalina.sh[9716]: 25-Dec-2018 05:20:34.695 > INFO > > [Thread-5] org.apache.coyote.AbstractProtocol.destroy Destroying > > ProtocolHandler ["https-jsse-nio-8443"] > > Dec 25 05:20:34 vps169399 catalina.sh[9716]: 25-Dec-2018 05:20:34.696 > INFO > > [Thread-5] org.apache.coyote.AbstractProtocol.destroy Destroying > > ProtocolHandler ["ajp-nio-8009"] > > Dec 25 05:20:34 vps169399 systemd[1]: tomcat.service: Control process > > exited, code=exited status=143 > > Dec 25 05:20:34 vps169399 sudo[9713]: pam_unix(sudo:session): session > > closed for user root > > Dec 25 05:20:34 vps169399 systemd[1]: tomcat.service: Failed with result > > 'timeout'. > > Dec 25 05:20:34 vps169399 systemd[1]: Failed to start Apache Tomcat Web > > Application Container. > > > > > > -- > name:李洋 > celephone：15011548154 > --- Nope. Daemon service set `Type=forking` is correct, startup.sh will fork new thread actually. The fail reason is startup timeout.
--- Dec 25 05:19:04 vps169399 catalina.sh[9716]: NOTE: Picked up JDK_JAVA_OPTIONS: --add-opens=java.base/java.lang=ALL-UNNAMED --add-opens=java.base/java.io=ALL-UNNAMED --add-opens=java.rmi/sun.rmi.transport=ALL-UNNAMED Dec 25 05:20:34 vps169399 systemd[1]: tomcat.service: Start operation timed out. Terminating. The default TimeoutStopSec is 90s ( `cat /etc/systemd/system.conf | grep Default` ), add "TimeoutStopSec=180" to your systemd script and give it a try.
