"File not found" error is not output to exception.log

Question

I use ColdFusion2021 update13, uses 2 logs that output to the api.log file (other logs output to other files)
- one uses log4j2 (of update13) with CreateObject("java", "org.apache.logging.log4j.LogManager"),
- the other uses my own build class CreateObject("java", "MyApiLog") (simply only logger.warn(str))
(both use 2 separate .properties files)
and uses the error-page <location>/Error404.cfm</location> handler in web.xml

After starting app server
At the Login screen (e.g. localhost:8500/index.cfm), when I access a non-existent file (e.g. localhost:8500/index.cfma)
the Error404.cfm runs OK and a "File not found" error (coldfusion.filter.ExceptionFilter$ApplicationExceptionWrapper: File not found: /Error404.cfm The specific sequence of files included or processed is: '''') is output to exception.log (up to here everything is OK)

After login ok (eg: localhost:8500/home/index.cfm), when I access a non-existent file (eg: localhost:8500/home/index.cfma)
the Error404.cfm runs OK but a "File not found" error is not output to exception.log but to the api.log
Why is the cause?

VietTruong · Accepted Answer

BKBKThank you for your response!I say "the Error404.cfm runs OK", i mean "Missing Template Handler" has no problem.Regarding the cfma file type i used, it was incorrect. I corrected it to use a .cfm file with any file name that doesn't exist.(as step2.cfm, step4.cfm below)About CF2018 -> CF2021 we don't need to care anymore, we are only mention to CF2021 update10 -> update11.About CGI too, we don't need to care anymore, we are only mention to the content we want to be logged.The content in exception.log is logged from CF.The content in api.log is logged from myapp (not CF).I guess the problem might be with the log4j2 (2.17.2(CF21 update10) -> 2.20.0(CF21 update11))In short, let's start over as below.Premise:\Error404.cfm (in Missing Template Handler).. \WEB-INF\...\CustomTags\Log.cfm (for debug checklog).. \step1.cfm \step3.cfm \WEB-INF\...\classes\(my package xxx)\api4j.propertieslog4j.rootLogger=WARN, R5log4j.appender.R5.Threshold=WARNlog4j.appender.R5 = org.apache.log4j.DailyRollingFileAppenderlog4j.appender.R5.File = C:\ColdFusion2021\mywebapp\logs\apilog\api.loglog4j.appender.R5.Append = truelog4j.appender.R5.DatePattern = '.'yyyy-MM-ddlog4j.appender.R5.layout = org.apache.log4j.PatternLayoutlog4j.appender.R5.layout.ConversionPattern = %d{yyyy-MM-dd HH:mm:ss} - %m%n \WEB-INF\...\classes\(my package xxx)\MyApiLog.java package xxx..api;import java.io.IOException;import java.util.Properties;import org.apache.log4j.Logger;import org.apache.log4j.PropertyConfigurator;public class MyApiLog {public static Logger logger = Logger.getLogger(MyApiLog.class.getName());private final String prop_file = "api4j.properties";public MyApiLog(){Properties props = new Properties();try {props.load(getClass().getResourceAsStream(prop_file));} catch (IOException e) {e.printStackTrace();}PropertyConfigurator.configure(props);}public void WriteLogger(String str){ logger.warn(str);}public Object getApi1() throws IOException {String str = "[SessionID] 25414FE0AD8D.. - [api] Api.getApi1 - [code] 200 - [time] 98ms";this.WriteLogger(str);return null;}} After run 4 scenarios below:step 1/access existent-file.cfm (localhost:8502/step1.cfm)step 2/access non-existent-file.cfm (localhost:8502/step2.cfm)step 3/access existent-file.cfm (localhost:8502/step3.cfm)step 4/access non-existent-file.cfm (localhost:8500/step4.cfm) Result (NG) on CF21 update11.(in api.log file there should be only 2 lines similar to update10 below) application.log-- step 1 start --"Error","http-nio-8502-exec-2","08/19/24","11:40:11","MyApp","checkLog Type=apij Message=log api by cfhttp""Error","http-nio-8502-exec-2","08/19/24","11:40:12","MyApp","checkLog Type=User Message="-- step 1 end ---- step 2 start --"Error","http-nio-8502-exec-3","08/19/24","11:41:18","MyApp","checkLog Type=user Message=Error404 run OK""Error","http-nio-8502-exec-3","08/19/24","11:41:18","MyApp","File not found: /step2.cfm The specific sequence of files included or processed is: C:\ColdFusion2021\mywebapp\wwwroot\step2.cfm''"-- step 2 end ---- step 3 start ---- step 3 end ---- step 4 start ---- step 4 end -- exception.log-- step 1 start --"Error","http-nio-8502-exec-2","08/19/24","11:40:11","MyApp","checkLog Type=apij Message=log api by cfhttp""Error","http-nio-8502-exec-2","08/19/24","11:40:12","MyApp","checkLog Type=User Message="-- step 1 end ---- step 2 start --"Error","http-nio-8502-exec-3","08/19/24","11:41:18","MyApp","checkLog Type=user Message=Error404 run OK""Error","http-nio-8502-exec-3","08/19/24","11:41:18","MyApp","File not found: /step2.cfm The specific sequence of files included or processed is: C:\ColdFusion2021\mywebapp\wwwroot\step2.cfm''"coldfusion.runtime.TemplateNotFoundException: File not found: /step2.cfm..(omit)at java.base/java.lang.Thread.run(Thread.java:834)-- step 2 end ---- step 3 start ---- step 3 end ---- step 4 start ---- step 4 end -- api.log (Orange lines should not be in here)-- step 1 start --2024-08-19 11:40:11 - log api by cfhttp-- step 1 end ---- step 2 start ---- step 2 end ---- step 3 start --2024-08-19 11:42:29 - [SessionID] 25414FE0AD8D.. - [api] Api.getApi1 - [code] 200 - [time] 98ms2024-08-19 11:42:29 - checkLog Type=User Message=-- step 3 end ---- step 4 start --2024-08-19 11:43:38 - checkLog Type=user Message=Error404 run OK2024-08-19 11:43:38 - File not found: /step4.cfm The specific sequence of files included or processed is: C:\ColdFusion2021\mywebapp\wwwroot\step4.cfm''coldfusion.filter.ExceptionFilter$ApplicationExceptionWrapper: File not found: /step4.cfm The specific sequence of files included or processed is: C:\ColdFusion2021\mywebapp\wwwroot\step4.cfm''..(omit)at java.lang.Thread.run(Thread.java:834) [?:?]-- step 4 end --Result (OK) on CF21 update10 (downgraded). application.log-- step 1 start --"Error","http-nio-8502-exec-5","08/19/24","11:55:20","MyApp","checkLog Type=apij Message=log api by cfhttp""Error","http-nio-8502-exec-5","08/19/24","11:55:21","MyApp","checkLog Type=User Message="-- step 1 end ---- step 2 start --"Error","http-nio-8502-exec-8","08/19/24","11:56:11","MyApp","checkLog Type=user Message=Error404 run OK""Error","http-nio-8502-exec-8","08/19/24","11:56:11","MyApp","File not found: /step2.cfm The specific sequence of files included or processed is: C:\ColdFusion2021\mywebapp\wwwroot\step2.cfm'' "-- step 2 end ---- step 3 start --"Error","http-nio-8502-exec-9","08/19/24","11:56:57","MyApp","checkLog Type=User Message="-- step 3 end ---- step 4 start --"Error","http-nio-8502-exec-3","08/19/24","11:57:48","MyApp","checkLog Type=user Message=Error404 run OK""Error","http-nio-8502-exec-3","08/19/24","11:57:48","MyApp","File not found: /step4.cfm The specific sequence of files included or processed is: C:\ColdFusion2021\mywebapp\wwwroot\step4.cfm'' "-- step 4 end -- exception.log-- step 1 start ---- step 1 end ---- step 2 start --"Error","http-nio-8502-exec-8","08/19/24","11:56:11","MyApp","File not found: /step2.cfm The specific sequence of files included or processed is: C:\ColdFusion2021\mywebapp\wwwroot\step2.cfm'' "coldfusion.runtime.TemplateNotFoundException: File not found: /step2.cfm..(omit)at java.base/java.lang.Thread.run(Thread.java:834)-- step 2 end ---- step 3 start ---- step 3 end ---- step 4 start --"Error","http-nio-8502-exec-3","08/19/24","11:57:48","MyApp","File not found: /step4.cfm The specific sequence of files included or processed is: C:\ColdFusion2021\mywebapp\wwwroot\step4.cfm'' "coldfusion.runtime.TemplateNotFoundException: File not found: /step4.cfm..(omit)at java.base/java.lang.Thread.run(Thread.java:834)-- step 4 end -- api.log-- step 1 start --2024-08-19 11:55:21 - log api by cfhttp-- step 1 end ---- step 2 start ---- step 2 end ---- step 3 start --2024-08-19 11:56:57 - [SessionID] 25414FE0AD8D.. - [api] Api.getApi1 - [code] 200 - [time] 98ms-- step 3 end ---- step 4 start ---- step 4 end --Charlie, BKBKNow, my problem has been solved.I just rewrote the 2 files below in log4j2 format.-api4j.properties (logger.api.name = API)-MyApiLog.java (logger = context.getLogger("API");)I would like to close this conversation.Thank you for your help!

Charlie Arehart · Answer

I have a different take than bkbk's reply today. Rather then being concerned about you causing "too many changes happening at the same time", my concern is that you are juggling (and making us all juggle) too many variables.

1) For example, you're showing cf as on Windows and Linux--with the latter being cf on Jboss as a war. I appreciate that you were making the point that the problem happens on both. But since War deployment of cf is niche (limited in number)--and on jboss specifically is still more niche--let's stick with discussing what you experience with CF on Windows, unless and until you really have need to make a distinction between the two variants. That will simplify things here.

1a) Second, your comments show running your Windows deployment of cf is as a cf instance called mywebapp. That was not indicated in your first post.

Indeed, I replied talking about how use of port 8500 implies your storing code in the cfusion/wwwroot, and you didn't correct me/us that you use /mywebapp/wwwroot. You simply showed using it in reply, and it's a subtle difference we had to discern. It might even be an important distinction: you'd definitely need to configure and look for things in different folders reflecting the correct instance. (And FWIW, your linux/jboss results then show you using cf's provided cfusion.war there, which I realize is the default war file name, but it could confuse matters for some trying to follow along.)

But perhaps more than important, when you show using port 8500, which was that on? Windows or Linux? Because when cf is installed as you have on Windows (NOT as a war), it defaults to using port 8500 for the cf admin and it uses the wwwroot (and logs) of the cfusion instance. Any new instance gets its own port, and a folder for the instance as a sibling to cfusion.

1b) So could you just discuss things simply in the context of Windows and perhaps do things only in your cfusion instance? This will both simplify things for us and also increase the chance for you that more people reading along can relate (since the number of folks running cf as multiple instances is a minority).

2) But most important, you're really throwing a curve ball with this whole matter of your java logging. And I get it: it's fundamental to what you want to do. But in the course of trying to understand how things are different for you since the move from CF2018 to cf2021, you've introduced not only the variety of differences I've relayed here, but it seems you're especially wanting to understand how 404 handling differs for you between cf2021 and 2018...and that on top of your own Java-based logging. Again, it's just too many variables.

2a) I'd strongly suggest you reduce your testing to one thing: in a stock deployment of cf2021 (on windows, in the cfusion/wwwroot, using port 8500),what happens for you when you run your 4 base scenarios?

Then introduce different variables, like adding a cflog, or adding your custom tag, or modifying any cf xml files. Then perhaps later try using a different instance.

Perhaps by starting from that "back to square one" approach, something may be more clear to you. Or it may be more clear to us.

3) Finally, I caught one key point you made, that the logging of things differed based on when you had or had not "logged in". That's certainly interesting, and you may want to elaborate on that.

Perhaps that's even possibly the most important point for you--but it's being lost in a flood of other concerns (and competing variables) being raised here.

Please make your testing as simple as possible. You may even find the answer on your own, suddenly. 🙂

Sign up

To post, reply, or follow discussions, please sign in with your Adobe ID.

Sign in to Adobe Community

To post, reply, or follow discussions, please sign in with your Adobe ID.

Scanning file for viruses.

This file cannot be downloaded