cfhttp timeout doesn't work with lucee 4.5.2.008 and java 8 jre

Bruce Kirkpatrick

unread,

Nov 1, 2015, 4:51:41 PM11/1/15

to Lucee

It seems cfhttp lucee 4.5.2.008 with java 8 as JRE doesn't work with cfhttp timeout attribute. It is so bad that the global requesttimeout doesn't work either, which means that you can run out of available tomcat threads, and effectively be offline. For some reason it ran for 128 seconds and then said "request timeout occured!". My system is not configured for 128 seconds, it is configured to 50 seconds in the web admin. This timeout of 128 seconds is something happening at the java level to prevent server hang I guess.

Instead of timing out according to CFHTTP, the HTTP tag runs until the global request timeout is reached. This may cause other problems like the following 2 exception log entries and it may have allowed tomcat server to run out of threads due to these slow httpd calls because my symptoms were mysterious connection failures until I rebooted everything. It was also failing to execute the application.cfc onError function. I had to find this in requesttimeout.log instead. There was an http call in my app that is no longer functioning (hangs instead of 404), so I'm not trying to debug this. Just reporting the issue.

Was this fixed already in a newer version by chance?

"ERROR","Thread-319","11/01/2015","16:25:11","","Thread.stop(Throwable) is not supported by this JVM and failed with UnsupportedOperationException;java.lang.Throwable;java.lang.Throwable

at java.net.PlainSocketImpl.socketConnect(Native Method)

at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:345)

at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206)

at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188)

at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)

at java.net.Socket.connect(Socket.java:589)

at org.apache.http.conn.socket.PlainConnectionSocketFactory.connectSocket(PlainConnectionSocketFactory.java:74)

at org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:134)

at org.apache.http.impl.conn.PoolingHttpClientConnectionManager.connect(PoolingHttpClientConnectionManager.java:353)

at org.apache.http.impl.execchain.MainClientExec.establishRoute(MainClientExec.java:380)

at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:236)

at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184)

at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88)

at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)

at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184)

at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)

at lucee.runtime.tag.Executor41.execute(Http41.java:1468)

at lucee.runtime.tag.Http41._doEndTag(Http41.java:992)

at lucee.runtime.tag.Http41.doEndTag(Http41.java:622)

at lucee.runtime.tag.HttpImpl.doEndTag(HttpImpl.java:223)

at vanndata_com.hp_cfm$cf.call(/var/jetendo-server/jetendo/sites/vanndata_com/hp.cfm:12)

at lucee.runtime.PageContextImpl.doInclude(PageContextImpl.java:953)

at lucee.runtime.PageContextImpl.doInclude(PageContextImpl.java:857)

at init.onrequest_cfm$cf.udfCall(/var/jetendo-server/jetendo/core/init/onRequest.cfm:66)

at lucee.runtime.type.UDFImpl.implementation(UDFImpl.java:111)

at lucee.runtime.type.UDFImpl._call(UDFImpl.java:328)

at lucee.runtime.type.UDFImpl.call(UDFImpl.java:227)

at lucee.runtime.ComponentImpl._call(ComponentImpl.java:641)

at lucee.runtime.ComponentImpl._call(ComponentImpl.java:524)

at lucee.runtime.ComponentImpl.call(ComponentImpl.java:1760)

at lucee.runtime.listener.ModernAppListener.call(ModernAppListener.java:405)

at lucee.runtime.listener.ModernAppListener._onRequest(ModernAppListener.java:221)

at lucee.runtime.listener.MixedAppListener.onRequest(MixedAppListener.java:35)

at lucee.runtime.PageContextImpl.execute(PageContextImpl.java:2264)

at lucee.runtime.PageContextImpl.execute(PageContextImpl.java:2227)

at lucee.runtime.engine.CFMLEngineImpl.serviceCFML(CFMLEngineImpl.java:456)

at lucee.loader.servlet.CFMLServlet.service(CFMLServlet.java:47)

at javax.servlet.http.HttpServlet.service(HttpServlet.java:729)

at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:291)

at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)

at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)

at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239)

at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)

at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:219)

at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:106)

at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:502)

at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:142)

at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:79)

at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:88)

at org.apache.catalina.valves.RemoteIpValve.invoke(RemoteIpValve.java:676)

at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:518)

at org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1091)

at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:668)

at org.apache.tomcat.util.net.AprEndpoint$SocketWithOptionsProcessor.run(AprEndpoint.java:2440)

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)

"ERROR","Thread-319","11/01/2015","16:25:11","",";org.apache.tomcat.util.threads.TaskThread.stop0(java.lang.Object);java.lang.NoSuchMethodException: org.apache.tomcat.util.threads.TaskThread.stop0(java.lang.Object)

at java.lang.Class.getMethod(Class.java:1786)

at lucee.commons.io.StopThread.run(SystemUtil.java:1071)

"

"ERROR","Thread-319","11/01/2015","16:25:12","","could not stop the thread on the first approach;java.lang.Throwable;java.lang.Throwable