One More Weird New Issue - Unmatched Tags

rd444 · June 1, 2019, 11:43am

Since upgrading, we’re getting these every 15-30 seconds in the application.log as well… Unless I’m crazy, the error doesn’t identify a page on which this is happening?

Application.log
“ERROR”,“http-nio-8888-exec-18”,“06/01/2019”,“07:40:00”,“”,";Start and End Tag has not the same Name [cfoutput-cfif];lucee.runtime.exp.TemplateException: Start and End Tag has not the same Name [cfoutput-cfif]
at lucee.transformer.cfml.tag.CFMLTransformer.tag(CFMLTransformer.java:817)
at lucee.transformer.cfml.tag.CFMLTransformer.body(CFMLTransformer.java:376)
at lucee.transformer.cfml.tag.CFMLTransformer.body(CFMLTransformer.java:384)
at lucee.transformer.cfml.tag.CFMLTransformer.body(CFMLTransformer.java:384)
at lucee.transformer.cfml.tag.CFMLTransformer.body(CFMLTransformer.java:384)
at lucee.transformer.cfml.tag.CFMLTransformer.body(CFMLTransformer.java:384)
at lucee.transformer.cfml.tag.CFMLTransformer.tag(CFMLTransformer.java:765)
at lucee.transformer.cfml.tag.CFMLTransformer.body(CFMLTransformer.java:376)
at lucee.transformer.cfml.tag.CFMLTransformer.body(CFMLTransformer.java:384)
at lucee.transformer.cfml.tag.CFMLTransformer.body(CFMLTransformer.java:384)
at lucee.transformer.cfml.tag.CFMLTransformer.transform(CFMLTransformer.java:314)
at lucee.transformer.cfml.tag.CFMLTransformer.transform(CFMLTransformer.java:306)
at lucee.transformer.cfml.tag.CFMLTransformer.transform(CFMLTransformer.java:162)
at lucee.runtime.compiler.CFMLCompilerImpl._compile(CFMLCompilerImpl.java:125)
at lucee.runtime.compiler.CFMLCompilerImpl.compile(CFMLCompilerImpl.java:76)
at lucee.runtime.PageSourceImpl._compile(PageSourceImpl.java:402)
at lucee.runtime.PageSourceImpl.compile(PageSourceImpl.java:369)
at lucee.runtime.PageSourceImpl.loadPhysical(PageSourceImpl.java:348)
at lucee.runtime.PageSourceImpl.loadPageThrowTemplateException(PageSourceImpl.java:220)
at lucee.runtime.PageSourceImpl.loadPage(PageSourceImpl.java:967)
at lucee.runtime.PageContextImpl._doInclude(PageContextImpl.java:937)
at lucee.runtime.PageContextImpl._doInclude(PageContextImpl.java:834)
at lucee.runtime.listener.ClassicAppListener._onRequest(ClassicAppListener.java:64)
at lucee.runtime.listener.MixedAppListener.onRequest(MixedAppListener.java:43)
at lucee.runtime.PageContextImpl.execute(PageContextImpl.java:2409)
at lucee.runtime.PageContextImpl._execute(PageContextImpl.java:2399)
at lucee.runtime.PageContextImpl.executeCFML(PageContextImpl.java:2374)
at lucee.runtime.engine.Request.exe(Request.java:43)
at lucee.runtime.engine.CFMLEngineImpl._service(CFMLEngineImpl.java:1037)
at lucee.runtime.engine.CFMLEngineImpl.serviceCFML(CFMLEngineImpl.java:983)
at lucee.loader.engine.CFMLEngineWrapper.serviceCFML(CFMLEngineWrapper.java:102)
at lucee.loader.servlet.CFMLServlet.service(CFMLServlet.java:62)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:729)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:230)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:165)
at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:192)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:165)
at org.apache.catalina.filters.CorsFilter.handleNonCORS(CorsFilter.java:419)
at org.apache.catalina.filters.CorsFilter.doFilter(CorsFilter.java:169)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:192)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:165)
at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:198)
at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:96)
at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:474)
at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:140)
at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:79)
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:87)
at org.apache.catalina.valves.RemoteIpValve.invoke(RemoteIpValve.java:677)
at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:349)
at org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:783)
at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:66)
at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:798)
at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1434)
at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
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)

Exception.log
“ERROR”,“http-nio-8888-exec-18”,“06/01/2019”,“07:40:00”,“”,";Start and End Tag has not the same Name [cfoutput-cfif];lucee.runtime.exp.TemplateException: Start and End Tag has not the same Name [cfoutput-cfif]
at lucee.transformer.cfml.tag.CFMLTransformer.tag(CFMLTransformer.java:817)
at lucee.transformer.cfml.tag.CFMLTransformer.body(CFMLTransformer.java:376)
at lucee.transformer.cfml.tag.CFMLTransformer.body(CFMLTransformer.java:384)
at lucee.transformer.cfml.tag.CFMLTransformer.body(CFMLTransformer.java:384)
at lucee.transformer.cfml.tag.CFMLTransformer.body(CFMLTransformer.java:384)
at lucee.transformer.cfml.tag.CFMLTransformer.body(CFMLTransformer.java:384)
at lucee.transformer.cfml.tag.CFMLTransformer.tag(CFMLTransformer.java:765)
at lucee.transformer.cfml.tag.CFMLTransformer.body(CFMLTransformer.java:376)
at lucee.transformer.cfml.tag.CFMLTransformer.body(CFMLTransformer.java:384)
at lucee.transformer.cfml.tag.CFMLTransformer.body(CFMLTransformer.java:384)
at lucee.transformer.cfml.tag.CFMLTransformer.transform(CFMLTransformer.java:314)
at lucee.transformer.cfml.tag.CFMLTransformer.transform(CFMLTransformer.java:306)
at lucee.transformer.cfml.tag.CFMLTransformer.transform(CFMLTransformer.java:162)
at lucee.runtime.compiler.CFMLCompilerImpl._compile(CFMLCompilerImpl.java:125)
at lucee.runtime.compiler.CFMLCompilerImpl.compile(CFMLCompilerImpl.java:76)
at lucee.runtime.PageSourceImpl._compile(PageSourceImpl.java:402)
at lucee.runtime.PageSourceImpl.compile(PageSourceImpl.java:369)
at lucee.runtime.PageSourceImpl.loadPhysical(PageSourceImpl.java:348)
at lucee.runtime.PageSourceImpl.loadPageThrowTemplateException(PageSourceImpl.java:220)
at lucee.runtime.PageSourceImpl.loadPage(PageSourceImpl.java:967)
at lucee.runtime.PageContextImpl._doInclude(PageContextImpl.java:937)
at lucee.runtime.PageContextImpl._doInclude(PageContextImpl.java:834)
at lucee.runtime.listener.ClassicAppListener._onRequest(ClassicAppListener.java:64)
at lucee.runtime.listener.MixedAppListener.onRequest(MixedAppListener.java:43)
at lucee.runtime.PageContextImpl.execute(PageContextImpl.java:2409)
at lucee.runtime.PageContextImpl._execute(PageContextImpl.java:2399)
at lucee.runtime.PageContextImpl.executeCFML(PageContextImpl.java:2374)
at lucee.runtime.engine.Request.exe(Request.java:43)
at lucee.runtime.engine.CFMLEngineImpl._service(CFMLEngineImpl.java:1037)
at lucee.runtime.engine.CFMLEngineImpl.serviceCFML(CFMLEngineImpl.java:983)
at lucee.loader.engine.CFMLEngineWrapper.serviceCFML(CFMLEngineWrapper.java:102)
at lucee.loader.servlet.CFMLServlet.service(CFMLServlet.java:62)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:729)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:230)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:165)
at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:192)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:165)
at org.apache.catalina.filters.CorsFilter.handleNonCORS(CorsFilter.java:419)
at org.apache.catalina.filters.CorsFilter.doFilter(CorsFilter.java:169)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:192)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:165)
at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:198)
at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:96)
at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:474)
at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:140)
at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:79)
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:87)
at org.apache.catalina.valves.RemoteIpValve.invoke(RemoteIpValve.java:677)
at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:349)
at org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:783)
at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:66)
at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:798)
at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1434)
at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
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)

rd444 · June 5, 2019, 12:02am

Any ideas? It’s still happening 5-6 times a minute but no references to what template is causing the error in either Application or Exception.log. Could it be something internal? It didn’t happen until the upgrade.

“ERROR”,“http-nio-8888-exec-99”,“06/04/2019”,“20:03:47”,“”,";Start and End Tag has not the same Name [cfoutput-cfif];lucee.runtime.exp.TemplateException: Start and End Tag has not the same Name [cfoutput-cfif]
at lucee.transformer.cfml.tag.CFMLTransformer.tag(CFMLTransformer.java:817)
at lucee.transformer.cfml.tag.CFMLTransformer.body(CFMLTransformer.java:376)
at lucee.transformer.cfml.tag.CFMLTransformer.body(CFMLTransformer.java:384)
at lucee.transformer.cfml.tag.CFMLTransformer.body(CFMLTransformer.java:384)
at lucee.transformer.cfml.tag.CFMLTransformer.body(CFMLTransformer.java:384)
at lucee.transformer.cfml.tag.CFMLTransformer.body(CFMLTransformer.java:384)
at lucee.transformer.cfml.tag.CFMLTransformer.tag(CFMLTransformer.java:765)
at lucee.transformer.cfml.tag.CFMLTransformer.body(CFMLTransformer.java:376)
at lucee.transformer.cfml.tag.CFMLTransformer.body(CFMLTransformer.java:384)
at lucee.transformer.cfml.tag.CFMLTransformer.body(CFMLTransformer.java:384)
at lucee.transformer.cfml.tag.CFMLTransformer.transform(CFMLTransformer.java:314)
at lucee.transformer.cfml.tag.CFMLTransformer.transform(CFMLTransformer.java:306)
at lucee.transformer.cfml.tag.CFMLTransformer.transform(CFMLTransformer.java:162)
at lucee.runtime.compiler.CFMLCompilerImpl._compile(CFMLCompilerImpl.java:125)
at lucee.runtime.compiler.CFMLCompilerImpl.compile(CFMLCompilerImpl.java:76)
at lucee.runtime.PageSourceImpl._compile(PageSourceImpl.java:402)
at lucee.runtime.PageSourceImpl.compile(PageSourceImpl.java:369)
at lucee.runtime.PageSourceImpl.loadPhysical(PageSourceImpl.java:315)
at lucee.runtime.PageSourceImpl.loadPageThrowTemplateException(PageSourceImpl.java:220)
at lucee.runtime.PageSourceImpl.loadPage(PageSourceImpl.java:967)
at lucee.runtime.PageContextImpl._doInclude(PageContextImpl.java:937)
at lucee.runtime.PageContextImpl._doInclude(PageContextImpl.java:834)
at lucee.runtime.listener.ClassicAppListener._onRequest(ClassicAppListener.java:64)
at lucee.runtime.listener.MixedAppListener.onRequest(MixedAppListener.java:43)
at lucee.runtime.PageContextImpl.execute(PageContextImpl.java:2409)
at lucee.runtime.PageContextImpl._execute(PageContextImpl.java:2399)
at lucee.runtime.PageContextImpl.executeCFML(PageContextImpl.java:2374)
at lucee.runtime.engine.Request.exe(Request.java:43)
at lucee.runtime.engine.CFMLEngineImpl._service(CFMLEngineImpl.java:1037)
at lucee.runtime.engine.CFMLEngineImpl.serviceCFML(CFMLEngineImpl.java:983)
at lucee.loader.engine.CFMLEngineWrapper.serviceCFML(CFMLEngineWrapper.java:102)
at lucee.loader.servlet.CFMLServlet.service(CFMLServlet.java:62)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:729)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:230)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:165)
at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:192)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:165)
at org.apache.catalina.filters.CorsFilter.handleNonCORS(CorsFilter.java:419)
at org.apache.catalina.filters.CorsFilter.doFilter(CorsFilter.java:169)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:192)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:165)
at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:198)
at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:96)
at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:474)
at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:140)
at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:79)
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:87)
at org.apache.catalina.valves.RemoteIpValve.invoke(RemoteIpValve.java:677)
at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:349)
at org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:783)
at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:66)
at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:798)
at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1434)
at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
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)

Zackster · June 5, 2019, 12:04pm

I have filed a bug about the logged error message not including template information.
https://luceeserver.atlassian.net/browse/LDEV-2305

if you wrap a try catch around your code, you can see the template in the detail exception.

otherwise, you should be able to find the url of any request that is throwing a 500 error from your webserver logs and work back from there

rd444 · June 5, 2019, 1:57pm

Thank you for the bug filing. Unfortunately I’m not seeing anything in our Apache error logs that matches up at all with these errors. Any Lucee errors seem to only be in the Lucee logs.

We’ve got more than 1500 templates, so I don’t think wrapping catches would be feasible without being able to narrow down where it might be occurring…

Zackster · June 5, 2019, 3:09pm

OnError in application.cfc?

andreas · June 5, 2019, 7:36pm

Depending on your traffic (if its not too high) I would try checking out the webservers logfiles and cross reference requests time wirh the time of the error message. But of course, depends on how much traffic you have. But that could help guessing a little.

rd444 · June 5, 2019, 7:50pm

Unfortunately (or fortunately!) we get at least a few hundred pages hit every second which is what made it such a needle in a hay stack to find this error. The good news is that we found the page in question via a Google Webmaster Tools (or whatever it’s called this week) report on Crawl errors and fixed the stupid coding error.

Zackster · June 5, 2019, 8:07pm

One trick I just remembered is to create a mapping and attempt to compile it into an archive via the admin. It will stop and report back on any compilation errors.

Lucee since 5.3.2 does this as part of the build process to validate thay all cfc/cfm’s shipped as part of a release are valid.