Cache issues only sometimes like a 0.5% chance of happening if not smaller

I have seen this a number of times now, and I am not sure why. But I can tell you that change no code, refresh the page and it works again. Refresh the page or just browse normally and every now and then this will happen.

If it helps this is ColdFusion 9.01 patched to the latest hotfix, but I have seen it on ColdFusion 9.01 without any hotfix.

Application Execution Exception

Error Type: AbstractAppender.NotImplementedException : [N/A]
Error Messages: This appender ‘coldbox.system.logging.appenders.AsyncRollingFileAppender’ must implement the ‘logMessage()’ method.

Regards,

Andrew Scott

http://www.andyscott.id.au/

I think this one is a coldfusion cfthread issue as it seems it has called the base classes logMessage() instead of the concrete class asyncRollingFileAppender. I think cfthread is loosing scope under load for some reason.

Don’t know how to tackle this one.

Luis F. Majano
President
Ortus Solutions, Corp

ColdBox Platform: http://www.coldbox.org
Linked In: http://www.linkedin.com/pub/3/731/483
Blog: http://www.luismajano.com
IECFUG Manager: http://www.iecfug.com

Ok, Andrew, let’s do this test just to see if it works.

Open the following file: coldbox.system.logging.appenders.AsyncFileAppender and go to line 84 where this is

Kk, haven’t noticed anything so far. But I just want to say there is no load on this, this was just me browsing my dev machine checking the links on a few pages. I got it to happen about 1-20 page hits about 5 times, but so far with those changes. No error as yet.

Regards,

Andrew Scott

http://www.andyscott.id.au/

Spoke to soon.

Framework Initialization/Configuration Exception

Error Type: Object : [N/A]
Error Messages: The logMessage method was not found.
Either there are no methods with the specified method name and argument types or the logMessage method is overloaded with argument types that ColdFusion cannot decipher reliably. ColdFusion found 0 methods that match the provided arguments. If this is a Java object and you verified that the method exists, use the javacast function to reduce ambiguity.

Regards,

Andrew Scott

http://www.andyscott.id.au/

Andrew,

Do you have config auto reload turned on?

Luis F. Majano
President
Ortus Solutions, Corp

ColdBox Platform: http://www.coldbox.org
Linked In: http://www.linkedin.com/pub/3/731/483
Blog: http://www.luismajano.com
IECFUG Manager: http://www.iecfug.com

No I don’t.

This might seem a bit out of left field, but I am thinking that it is cached related. I had it happen, after I had not browsed the app for a few minutes. As I stated earlier this is in development and I am the only one hitting the site, and I have also see other messages that are cache related that I have posted to here as well.

But this is a guess on my part.

Regards,

Andrew Scott

http://www.andyscott.id.au/

Well, the appenders are not using the cache. That is just logbox via cfthread
How can I recreate this?

Luis F. Majano
President
Ortus Solutions, Corp

ColdBox Platform: http://www.coldbox.org
Linked In: http://www.linkedin.com/pub/3/731/483
Blog: http://www.luismajano.com
IECFUG Manager: http://www.iecfug.com

I just build a load test on jmeter of 200 users hitting an ap every 1 second for 5 rounds with a simple handler just using the log.info()

no config auto reload, no debug mode.

Nothing.

Luis F. Majano
President
Ortus Solutions, Corp

ColdBox Platform: http://www.coldbox.org
Linked In: http://www.linkedin.com/pub/3/731/483
Blog: http://www.luismajano.com
IECFUG Manager: http://www.iecfug.com

That is a very good question, I am still trying to find a method to replicate it reliably.

Regards,

Andrew Scott

http://www.andyscott.id.au/

Like I said it is certainly not a load problem, as I am the only user testing on this server. And one case I had touched the browser for a few mins, then when I clicked on a link in the application I got the last message posted.

Maybe it is a combination of my config settings, worst case I can zip up my application and get you to try it.

Now the thing is that I am personally not using any logging at this stage, so it has to be a system log problem as well. Next time I can duplicate it I’ll get the stack trace for you.

Regards,

Andrew Scott

http://www.andyscott.id.au/

Ok here is the stack trace to this error, not sure if it helps or not.

Application Execution Exception

Error Type: Autowire.AutowireException : [N/A]
Error Messages: Error autowiring system.modules.blog.controllers.blog. This appender ‘coldbox.system.logging.appenders.AsyncRollingFileAppender’ must implement the ‘logMessage()’ method.
coldfusion.runtime.CustomException: This appender ‘coldbox.system.logging.appenders.AsyncRollingFileAppender’ must implement the ‘logMessage()’ method. at coldfusion.tagext.lang.ThrowTag.doStartTag(ThrowTag.java:142) at coldfusion.runtime.CfJspPage._emptyTcfTag(CfJspPage.java:2722) at cfAbstractAppender2ecfc424774562$funcLOGMESSAGE.runFunction(D:\projects\SpringField Release\coldbox\system\logging\AbstractAppender.cfc:161) at coldfusion.runtime.UDFMethod.invoke(UDFMethod.java:472) at coldfusion.filter.SilentFilter.invoke(SilentFilter.java:47) at coldfusion.runtime.UDFMethod$ReturnTypeFilter.invoke(UDFMethod.java:405) at coldfusion.runtime.UDFMethod$ArgumentCollectionFilter.invoke(UDFMethod.java:368) at coldfusion.filter.FunctionAccessFilter.invoke(FunctionAccessFilter.java:55) at coldfusion.runtime.UDFMethod.runFilterChain(UDFMethod.java:321) at coldfusion.runtime.UDFMethod.invoke(UDFMethod.java:220) at coldfusion.runtime.CfJspPage._invokeUDF(CfJspPage.java:2582) at coldfusion.runtime.CfJspPage._invoke(CfJspPage.java:2383) at cfAsyncRollingFileAppender2ecfc1005236113$funcLOGMESSAGE.runFunction(D:\projects\SpringField Release\coldbox\system\logging\appenders\AsyncRollingFileAppender.cfc:61) at coldfusion.runtime.UDFMethod.invoke(UDFMethod.java:472) at coldfusion.filter.SilentFilter.invoke(SilentFilter.java:47) at coldfusion.runtime.UDFMethod$ReturnTypeFilter.invoke(UDFMethod.java:405) at coldfusion.runtime.UDFMethod$ArgumentCollectionFilter.invoke(UDFMethod.java:368) at coldfusion.filter.FunctionAccessFilter.invoke(FunctionAccessFilter.java:55) at coldfusion.runtime.UDFMethod.runFilterChain(UDFMethod.java:321) at coldfusion.runtime.UDFMethod.invoke(UDFMethod.java:220) at coldfusion.runtime.TemplateProxy.invoke(TemplateProxy.java:491) at coldfusion.runtime.TemplateProxy.invoke(TemplateProxy.java:337) at coldfusion.runtime.CfJspPage._invoke(CfJspPage.java:2360) at cfLogger2ecfc696828893$funcLOGMESSAGE.runFunction(D:\projects\SpringField Release\coldbox\system\logging\Logger.cfc:327) at coldfusion.runtime.UDFMethod.invoke(UDFMethod.java:472) at coldfusion.filter.SilentFilter.invoke(SilentFilter.java:47) at coldfusion.runtime.UDFMethod$ReturnTypeFilter.invoke(UDFMethod.java:405) at coldfusion.runtime.UDFMethod$ArgumentCollectionFilter.invoke(UDFMethod.java:368) at coldfusion.filter.FunctionAccessFilter.invoke(FunctionAccessFilter.java:55) at coldfusion.runtime.UDFMethod.runFilterChain(UDFMethod.java:321) at coldfusion.runtime.UDFMethod.invoke(UDFMethod.java:517) at coldfusion.runtime.CfJspPage._invokeUDF(CfJspPage.java:2547) at cfLogger2ecfc696828893$funcDEBUG.runFunction(D:\projects\SpringField Release\coldbox\system\logging\Logger.cfc:231) at coldfusion.runtime.UDFMethod.invoke(UDFMethod.java:472) at coldfusion.filter.SilentFilter.invoke(SilentFilter.java:47) at coldfusion.runtime.UDFMethod$ReturnTypeFilter.invoke(UDFMethod.java:405) at coldfusion.runtime.UDFMethod$ArgumentCollectionFilter.invoke(UDFMethod.java:368) at coldfusion.filter.FunctionAccessFilter.invoke(FunctionAccessFilter.java:55) at coldfusion.runtime.UDFMethod.runFilterChain(UDFMethod.java:321) at coldfusion.runtime.UDFMethod.invoke(UDFMethod.java:220) at coldfusion.runtime.TemplateProxy.invoke(TemplateProxy.java:491) at coldfusion.runtime.TemplateProxy.invoke(TemplateProxy.java:337) at coldfusion.runtime.CfJspPage._invoke(CfJspPage.java:2360) at cfBeanFactory2ecfc1930679405$funcAUTOWIRE.runFunction(D:\projects\SpringField Release\coldbox\system\plugins\BeanFactory.cfc:659) at coldfusion.runtime.UDFMethod.invoke(UDFMethod.java:472) at coldfusion.filter.SilentFilter.invoke(SilentFilter.java:47) at coldfusion.runtime.UDFMethod$ReturnTypeFilter.invoke(UDFMethod.java:405) at coldfusion.runtime.UDFMethod$ArgumentCollectionFilter.invoke(UDFMethod.java:368) at coldfusion.filter.FunctionAccessFilter.invoke(FunctionAccessFilter.java:55) at coldfusion.runtime.UDFMethod.runFilterChain(UDFMethod.java:321) at coldfusion.runtime.UDFMethod.invoke(UDFMethod.java:517) at coldfusion.runtime.TemplateProxy.invoke(TemplateProxy.java:496) at coldfusion.runtime.TemplateProxy.invoke(TemplateProxy.java:355) at coldfusion.runtime.CfJspPage._invoke(CfJspPage.java:2301) at cfAutowire2ecfc2074090926$funcPROCESSAUTOWIRE.runFunction(D:\projects\SpringField Release\coldbox\system\interceptors\Autowire.cfc:139) at coldfusion.runtime.UDFMethod.invoke(UDFMethod.java:472) at coldfusion.filter.SilentFilter.invoke(SilentFilter.java:47) at coldfusion.runtime.UDFMethod$ReturnTypeFilter.invoke(UDFMethod.java:405) at coldfusion.runtime.UDFMethod$ArgumentCollectionFilter.invoke(UDFMethod.java:368) at coldfusion.filter.FunctionAccessFilter.invoke(FunctionAccessFilter.java:55) at coldfusion.runtime.UDFMethod.runFilterChain(UDFMethod.java:321) at coldfusion.runtime.UDFMethod.invoke(UDFMethod.java:220) at coldfusion.runtime.CfJspPage._invokeUDF(CfJspPage.java:2582) at cfAutowire2ecfc2074090926$funcAFTERHANDLERCREATION.runFunction(D:\projects\SpringField Release\coldbox\system\interceptors\Autowire.cfc:93) at coldfusion.runtime.UDFMethod.invoke(UDFMethod.java:472) at coldfusion.filter.SilentFilter.invoke(SilentFilter.java:47) at coldfusion.runtime.UDFMethod$ReturnTypeFilter.invoke(UDFMethod.java:405) at coldfusion.runtime.UDFMethod$ArgumentCollectionFilter.invoke(UDFMethod.java:368) at coldfusion.filter.FunctionAccessFilter.invoke(FunctionAccessFilter.java:55) at coldfusion.runtime.UDFMethod.runFilterChain(UDFMethod.java:321) at coldfusion.runtime.UDFMethod.invoke(UDFMethod.java:517) at coldfusion.runtime.TemplateProxy.invoke(TemplateProxy.java:496) at coldfusion.runtime.TemplateProxy.invoke(TemplateProxy.java:355) at coldfusion.runtime.CfJspPage._invoke(CfJspPage.java:2301) at

Regards,

Andrew Scott

http://www.andyscott.id.au/