With the Tomcat version 9.0.35, the following error occurs without deploying any custom application, 28-May-2020 06:08:44.960 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardServer[8005]] to [INITIALIZING] 28-May-2020 06:08:44.963 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server version name: Apache Tomcat/9.0.35 28-May-2020 06:08:44.963 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server built: May 5 2020 20:36:20 UTC 28-May-2020 06:08:44.963 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server version number: 9.0.35.0 28-May-2020 06:08:44.963 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log OS Name: Linux 28-May-2020 06:08:44.963 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log OS Version: 4.1.12-124.38.1.el6uek.x86_64 28-May-2020 06:08:44.963 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Architecture: amd64 28-May-2020 06:08:44.963 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Java Home: /usr/local/jdk/jre 28-May-2020 06:08:44.963 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Version: 1.8.0_251-b08 28-May-2020 06:08:44.964 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Vendor: Oracle Corporation 28-May-2020 06:08:44.964 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_BASE: /home/tomcat/test1/apache-tomcat-9.0.35 28-May-2020 06:08:44.964 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_HOME: /home/tomcat/test1/apache-tomcat-9.0.35 28-May-2020 06:08:44.964 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.config.file=/home/tomcat/test1/apache-tomcat-9 .0.35/conf/logging.properties 28-May-2020 06:08:44.964 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager 28-May-2020 06:08:44.964 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djdk.tls.ephemeralDHKeySize=2048 28-May-2020 06:08:44.964 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.protocol.handler.pkgs=org.apache.catalina.webresources 28-May-2020 06:08:44.964 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dorg.apache.catalina.security.SecurityListener.UMASK=0027 28-May-2020 06:08:44.964 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dignore.endorsed.dirs= 28-May-2020 06:08:44.965 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.base=/home/tomcat/test1/apache-tomcat-9.0.35 28-May-2020 06:08:44.965 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.home=/home/tomcat/test1/apache-tomcat-9.0.35 28-May-2020 06:08:44.965 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.io.tmpdir=/home/tomcat/test1/apache-tomcat-9.0.35/temp 28-May-2020 06:08:44.965 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent The Apache Tomcat Native library which allows using OpenSSL was not found on the j ava.library.path: [/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib] 28-May-2020 06:08:45.213 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [org.apache.catalina.deploy.NamingResourcesImpl@3c0f93f1] to [INITIAL IZING] 28-May-2020 06:08:45.215 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [org.apache.catalina.deploy.NamingResourcesImpl@3c0f93f1] to [INITIAL IZED] 28-May-2020 06:08:45.229 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardService[Catalina]] to [INITIALIZING] 28-May-2020 06:08:45.230 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardEngine[Catalina]] to [INITIALIZING] 28-May-2020 06:08:45.231 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardEngine[Catalina]] to [INITIALIZED] 28-May-2020 06:08:45.231 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [org.apache.catalina.mapper.MapperListener@6fc6f14e] to [INITIALIZING ] 28-May-2020 06:08:45.234 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [org.apache.catalina.mapper.MapperListener@6fc6f14e] to [INITIALIZED] 28-May-2020 06:08:45.234 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [Connector[HTTP/1.1-8080]] to [INITIALIZING] 28-May-2020 06:08:45.238 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-8080"] 28-May-2020 06:08:45.257 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [Connector[HTTP/1.1-8080]] to [INITIALIZED] 28-May-2020 06:08:45.257 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardService[Catalina]] to [INITIALIZED] 28-May-2020 06:08:45.257 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardServer[8005]] to [INITIALIZED] 28-May-2020 06:08:45.258 INFO [main] org.apache.catalina.startup.Catalina.load Server initialization in [441] milliseconds 28-May-2020 06:08:45.258 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardServer[8005]] to [STARTING_PREP] 28-May-2020 06:08:45.264 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardServer[8005]] to [STARTING] 28-May-2020 06:08:45.277 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [org.apache.catalina.deploy.NamingResourcesImpl@3c0f93f1] to [STARTIN G_PREP] 28-May-2020 06:08:45.277 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [org.apache.catalina.deploy.NamingResourcesImpl@3c0f93f1] to [STARTIN G] 28-May-2020 06:08:45.277 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [org.apache.catalina.deploy.NamingResourcesImpl@3c0f93f1] to [STARTED ] 28-May-2020 06:08:45.277 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardService[Catalina]] to [STARTING_PREP] 28-May-2020 06:08:45.277 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Catalina] 28-May-2020 06:08:45.277 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardService[Catalina]] to [STARTING] 28-May-2020 06:08:45.277 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardEngine[Catalina]] to [STARTING_PREP] 28-May-2020 06:08:45.277 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.35] 28-May-2020 06:08:45.278 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [LockOutRealm[StandardEngine[Catalina]]] to [INITIALIZING] 28-May-2020 06:08:45.278 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [LockOutRealm[StandardEngine[Catalina]]] to [INITIALIZED] 28-May-2020 06:08:45.278 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [LockOutRealm[StandardEngine[Catalina]]] to [STARTING_PREP] 28-May-2020 06:08:45.279 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [UserDatabaseRealm[StandardEngine[Catalina]]] to [INITIALIZING] 28-May-2020 06:08:45.279 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [UserDatabaseRealm[StandardEngine[Catalina]]] to [INITIALIZED] 28-May-2020 06:08:45.279 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [UserDatabaseRealm[StandardEngine[Catalina]]] to [STARTING_PREP] 28-May-2020 06:08:45.280 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [UserDatabaseRealm[StandardEngine[Catalina]]] to [STARTING] 28-May-2020 06:08:45.280 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [UserDatabaseRealm[StandardEngine[Catalina]]] to [STARTED] 28-May-2020 06:08:45.280 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [LockOutRealm[StandardEngine[Catalina]]] to [STARTING] 28-May-2020 06:08:45.280 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [LockOutRealm[StandardEngine[Catalina]]] to [STARTED] 28-May-2020 06:08:45.281 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardEngine[Catalina].StandardHost[localhost]] to [INITIALIZING] 28-May-2020 06:08:45.281 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardEngine[Catalina].StandardHost[localhost]] to [INITIALIZED] 28-May-2020 06:08:45.281 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardEngine[Catalina].StandardHost[localhost]] to [STARTING_PREP] 28-May-2020 06:08:45.284 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardPipeline[StandardEngine[Catalina].StandardHost[localhost]]] to [INITIALIZING] 28-May-2020 06:08:45.284 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardPipeline[StandardEngine[Catalina].StandardHost[localhost]]] to [INITIALIZED] 28-May-2020 06:08:45.284 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardPipeline[StandardEngine[Catalina].StandardHost[localhost]]] to [STARTING_PREP] 28-May-2020 06:08:45.284 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [AccessLogValve[StandardEngine[Catalina].StandardHost[localhost]]] to [INITIALIZING] 28-May-2020 06:08:45.284 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [AccessLogValve[StandardEngine[Catalina].StandardHost[localhost]]] to [INITIALIZED] 28-May-2020 06:08:45.284 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [AccessLogValve[StandardEngine[Catalina].StandardHost[localhost]]] to [STARTING_PREP] 28-May-2020 06:08:45.285 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [AccessLogValve[StandardEngine[Catalina].StandardHost[localhost]]] to [STARTING] 28-May-2020 06:08:45.285 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [AccessLogValve[StandardEngine[Catalina].StandardHost[localhost]]] to [STARTED] 28-May-2020 06:08:45.285 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [ErrorReportValve[StandardEngine[Catalina].StandardHost[localhost]]] to [INITIALIZING] 28-May-2020 06:08:45.285 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [ErrorReportValve[StandardEngine[Catalina].StandardHost[localhost]]] to [INITIALIZED] 28-May-2020 06:08:45.286 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [ErrorReportValve[StandardEngine[Catalina].StandardHost[localhost]]] to [STARTING_PREP] 28-May-2020 06:08:45.286 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [ErrorReportValve[StandardEngine[Catalina].StandardHost[localhost]]] to [STARTING] 28-May-2020 06:08:45.286 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [ErrorReportValve[StandardEngine[Catalina].StandardHost[localhost]]] to [STARTED] 28-May-2020 06:08:45.286 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardHostValve[StandardEngine[Catalina].StandardHost[localhost]]] to [INITIALIZING] 28-May-2020 06:08:45.286 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardHostValve[StandardEngine[Catalina].StandardHost[localhost]]] to [INITIALIZED] 28-May-2020 06:08:45.286 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardHostValve[StandardEngine[Catalina].StandardHost[localhost]]] to [STARTING_PREP] 28-May-2020 06:08:45.286 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardHostValve[StandardEngine[Catalina].StandardHost[localhost]]] to [STARTING] 28-May-2020 06:08:45.286 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardHostValve[StandardEngine[Catalina].StandardHost[localhost]]] to [STARTED] 28-May-2020 06:08:45.286 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardPipeline[StandardEngine[Catalina].StandardHost[localhost]]] to [STARTING] 28-May-2020 06:08:45.287 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardPipeline[StandardEngine[Catalina].StandardHost[localhost]]] to [STARTED] 28-May-2020 06:08:45.287 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardEngine[Catalina].StandardHost[localhost]] to [STARTING] 28-May-2020 06:08:45.287 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardEngine[Catalina].StandardHost[localhost]] to [STARTED] 28-May-2020 06:08:45.287 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardPipeline[StandardEngine[Catalina]]] to [INITIALIZING] 28-May-2020 06:08:45.287 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardPipeline[StandardEngine[Catalina]]] to [INITIALIZED] 28-May-2020 06:08:45.287 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardPipeline[StandardEngine[Catalina]]] to [STARTING_PREP] 28-May-2020 06:08:45.287 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardEngineValve[StandardEngine[Catalina]]] to [INITIALIZING] 28-May-2020 06:08:45.287 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardEngineValve[StandardEngine[Catalina]]] to [INITIALIZED] 28-May-2020 06:08:45.288 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardEngineValve[StandardEngine[Catalina]]] to [STARTING_PREP] 28-May-2020 06:08:45.288 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardEngineValve[StandardEngine[Catalina]]] to [STARTING] 28-May-2020 06:08:45.288 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardEngineValve[StandardEngine[Catalina]]] to [STARTED] 28-May-2020 06:08:45.288 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardPipeline[StandardEngine[Catalina]]] to [STARTING] 28-May-2020 06:08:45.288 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardPipeline[StandardEngine[Catalina]]] to [STARTED] 28-May-2020 06:08:45.288 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardEngine[Catalina]] to [STARTING] 28-May-2020 06:08:45.290 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardEngine[Catalina]] to [STARTED] 28-May-2020 06:08:45.290 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [org.apache.catalina.mapper.MapperListener@6fc6f14e] to [STARTING_PREP] 28-May-2020 06:08:45.290 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [org.apache.catalina.mapper.MapperListener@6fc6f14e] to [STARTING] 28-May-2020 06:08:45.291 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [org.apache.catalina.mapper.MapperListener@6fc6f14e] to [STARTED] 28-May-2020 06:08:45.291 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [Connector[HTTP/1.1-8080]] to [STARTING_PREP] 28-May-2020 06:08:45.291 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [Connector[HTTP/1.1-8080]] to [STARTING] 28-May-2020 06:08:45.291 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-8080"] 28-May-2020 06:08:45.309 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [Connector[HTTP/1.1-8080]] to [STARTED] 28-May-2020 06:08:45.309 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardService[Catalina]] to [STARTED] 28-May-2020 06:08:45.309 FINE [main] org.apache.catalina.util.LifecycleBase.setStateInternal Setting state for [StandardServer[8005]] to [STARTED] 28-May-2020 06:08:45.310 INFO [main] org.apache.catalina.startup.Catalina.start Server startup in [51] milliseconds 28-May-2020 06:08:46.094 INFO [http-nio-8080-exec-1] org.apache.coyote.http11.Http11Processor.service Error parsing HTTP request header Note: further occurrences of HTTP request parsing errors will be logged at DEBUG level. java.lang.IllegalStateException: Invalid request line parse phase [6] at org.apache.coyote.http11.Http11InputBuffer.parseRequestLine(Http11InputBuffer.java:579) at org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:260) at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:65) at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:868) at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1590) at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) at java.lang.Thread.run(Thread.java:748)
What kind of request do you make against Tomcat ? What headers are in the request ? The provided information is not enough to investigate any kind of problem. You better ask for help at users@tomcat.apache.org
A client appears to have sent an HTTP/1.1 request without a protocol. It may have been trying to send an HTTP/0.9 request but it included a trailing " " (space) after the URI. The users list is the place to seek additional help.
I have only start the Tomcat with all default settings and having now applications in /webapp. No external request is made. The error appears when it just starts. The other version i have checked (version 9.0.33) and there is no such error.
This behaviour is not observed in a clean 9.0.35 install. Something is creating the invalid request. Again, the users list is the place to seek help.
Hi Mark, Thanks. We have resolved the issue. The http request has been sent by reverse proxy from HTTP/0.9 protocol with the tailing double pair of RCFL which is not supported by HTTP/1.1. So we have changed the RCFL and then it works. Thanks.
Glad to see you fixed your issue. Restoring the correct status.
Hi Md. Adnan Rashidul. Need your support here, I am also facing the same issue as we are using Tomcat 9.0.36 behind F5 and F5 sendstring is GET /HealthInfo /r/n (In reply to Md. Adnan Rashidul Islam from comment #5) > Hi Mark, > > Thanks. We have resolved the issue. The http request has been sent by > reverse proxy from HTTP/0.9 protocol with the tailing double pair of RCFL > which is not supported by HTTP/1.1. So we have changed the RCFL and then it > works. Thanks.
*** Bug 64770 has been marked as a duplicate of this bug. ***
Hi Deviduttapanda, have you recently upgraded to Tomcat version 9.0.36? Is the issue occurred in the previous version? what was the previous version of Tomcat? Thanks. (In reply to Deviduttapanda from comment #7) > Hi Md. Adnan Rashidul. > > Need your support here, I am also facing the same issue as we are using > Tomcat 9.0.36 behind F5 and F5 sendstring is GET /HealthInfo /r/n > > > > > (In reply to Md. Adnan Rashidul Islam from comment #5) > > Hi Mark, > > > > Thanks. We have resolved the issue. The http request has been sent by > > reverse proxy from HTTP/0.9 protocol with the tailing double pair of RCFL > > which is not supported by HTTP/1.1. So we have changed the RCFL and then it > > works. Thanks.
Hi Adnan, yes we have recently upgraded to Tomcat 9.0.36 the previous version was 9.0.29 and it was working fine.
Hi, Please check the following, 01) Monitoring parent profile on LTM. Also you can check the send string on the parent profile. on the Parent profile the default should be GET /\r\n 02) Check if any extra space in the send string. 03) Additionally you can check if the correct output of /HealthInfo is set in the F5 monitoring (I assume it's already checked) If the above steps are all good then you may look into F5 devcentral for specific F5 version's instruction. Thanks, Adnan