Bug 48802 - Log4j not writting.
Summary: Log4j not writting.
Status: NEW
Alias: None
Product: Log4j - Now in Jira
Classification: Unclassified
Component: Appender (show other bugs)
Version: 1.2
Hardware: HP HP-UX
: P2 normal
Target Milestone: ---
Assignee: log4j-dev
URL:
Keywords:
Depends on:
Blocks:
 
Reported: 2010-02-23 11:05 UTC by pikapika2501
Modified: 2010-02-23 11:05 UTC (History)
0 users


Attachments
Add an attachment (proposed patch, testcase, etc.)

Note You need to log in before you can comment on or make changes to this bug.
Description pikapika2501 2010-02-23 11:05:49 UTC 
Our web production applcation is currently experiencing logging issues. Based on the frequency to whichh this happens is usually around thrice a year.

The nature to which this issue manifest can be described as our logFile being incomplete. Incomplete in a sense that a certain portion of our daily log is missing as describe below:


Also, it is worth to mention that we experience slow down with our application during the missing logs.

2010-02-15 15:43:18,313 WARN  [org.apache.tapestry.engine.AbstractEngine] Unable to process client request.
org.apache.tapestry.ApplicationRuntimeException: Unable to initialize property activeMerchantStore of inpowered.stock.warehousing.pages.SupplierReturnPage$Enhance_142@60e190b8[supplierreturn]: Unable to read expression '<parsed expression>' of inpowered.stock.warehousing.pages.SupplierReturnPage$Enhance_142@60e190b8[supplierreturn].
	at org.apache.tapestry.pageload.PropertyInitializer.pageDetached(PropertyInitializer.java:115)
	at org.apache.tapestry.pageload.PageLoader.establishDefaultPropertyValues(PageLoader.java:819)
	at org.apache.tapestry.pageload.PageLoader.loadPage(PageLoader.java:770)
	at org.apache.tapestry.pageload.PageSource.getPage(PageSource.java:153)
	at org.apache.tapestry.engine.RequestCycle.getPage(RequestCycle.java:196)
	at org.apache.tapestry.engine.DirectService.service(DirectService.java:127)
	at org.apache.tapestry.engine.AbstractEngine.service(AbstractEngine.java:872)
	at org.apache.tapestry.ApplicationServlet.doService(ApplicationServlet.java:197)
	at org.apache.tapestry.ApplicationServlet.doPost(ApplicationServlet.java:326)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:810)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:252)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173)
	at inpowered.framework.filter.RequestControlFilter.doFilter(RequestControlFilter.java:149)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:202)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173)
	at org.jboss.web.tomcat.filters.ReplyHeaderFilter.doFilter(ReplyHeaderFilter.java:81)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:202)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:178)
	at org.jboss.web.tomcat.security.CustomPrincipalValve.invoke(CustomPrincipalValve.java:39)
	at org.jboss.web.tomcat.security.SecurityAssociationValve.invoke(SecurityAssociationValve.java:159)
	at org.jboss.web.tomcat.security.JaccContextValve.invoke(JaccContextValve.java:59)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:126)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:105)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:107)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:148)
	at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:856)
	at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.processConnection(Http11Protocol.java:744)
	at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:527)
	at org.apache.tomcat.util.net.MasterSlaveWorkerThread.run(MasterSlaveWorkerThread.java:112)
	at java.lang.Thread.run(Thread.java:534)
Caused by: org.apache.tapestry.ApplicationRuntimeException: Unable to read expression '<parsed expression>' of inpowered.stock.warehousing.pages.SupplierReturnPage$Enhance_142@60e190b8[supplierreturn].
	at org.apache.tapestry.util.prop.OgnlUtils.get(OgnlUtils.java:137)
	at org.apache.tapestry.util.prop.OgnlUtils.get(OgnlUtils.java:158)
	at org.apache.tapestry.pageload.PropertyInitializer.pageDetached(PropertyInitializer.java:109)
	... 32 more
Caused by: ognl.OgnlException: allMerchant [java.lang.NullPointerException]
	at ognl.OgnlRuntime.getMethodValue(OgnlRuntime.java:732)
	at ognl.ObjectPropertyAccessor.getPossibleProperty(ObjectPropertyAccessor.java:54)
	at ognl.ObjectPropertyAccessor.getProperty(ObjectPropertyAccessor.java:122)
	at ognl.OgnlRuntime.getProperty(OgnlRuntime.java:1443)
	at ognl.ASTProperty.getValueBody(ASTProperty.java:96)
	at ognl.SimpleNode.getValue(SimpleNode.java:192)
	at ognl.Ognl.getValue(Ognl.java:335)
	at ognl.Ognl.getValue(Ognl.java:310)
	at org.apache.tapestry.util.prop.OgnlUtils.get(OgnlUtils.java:133)
	... 34 more
2010-02-15 15:43:18,329 WARN  [org.apache.tapestry.engine.AbstractEngine] Unable to present exception page.
org.apache.tapestry.PageRedirectException: index
	at inpowered.common.tapestry.BaseInpoweredPage.needToLogin(BaseInpoweredPage.java:103)
	at inpowered.common.tapestry.BaseInpoweredPage.pageBeginRender(BaseInpoweredPage.java:90)
	at org.apache.tapestry.AbstractPage.firePageBeginRender(AbstractPage.java:463)
	at org.apache.tapestry.AbstractPage.renderPage(AbstractPage.java:293)
	at org.apache.tapestry.engine.RequestCycle.renderPage(RequestCycle.java:371)
	at org.apache.tapestry.engine.AbstractEngine.renderResponse(AbstractEngine.java:732)
	at org.apache.tapestry.engine.AbstractEngine.activateExceptionPage(AbstractEngine.java:459)
	at org.apache.tapestry.engine.AbstractEngine.service(AbstractEngine.java:914)
	at org.apache.tapestry.ApplicationServlet.doService(ApplicationServlet.java:197)
	at org.apache.tapestry.ApplicationServlet.doPost(ApplicationServlet.java:326)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:810)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:252)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173)
	at inpowered.framework.filter.RequestControlFilter.doFilter(RequestControlFilter.java:149)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:202)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173)
	at org.jboss.web.tomcat.filters.ReplyHeaderFilter.doFilter(ReplyHeaderFilter.java:81)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:202)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:178)
	at org.jboss.web.tomcat.security.CustomPrincipalValve.invoke(CustomPrincipalValve.java:39)
	at org.jboss.web.tomcat.security.SecurityAssociationValve.invoke(SecurityAssociationValve.java:159)
	at org.jboss.web.tomcat.security.JaccContextValve.invoke(JaccContextValve.java:59)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:126)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:105)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:107)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:148)
	at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:856)
	at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.processConnection(Http11Protocol.java:744)
	at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:527)
	at org.apache.tomcat.util.net.MasterSlaveWorkerThread.run(MasterSlaveWorkerThread.java:112)
	at java.lang.Thread.run(Thread.java:534)
2010-02-15 15:43:18,358 ERROR [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost].[/IRIS]] inpowered: ServletException
javax.servlet.ServletException: index
	at org.apache.tapestry.engine.AbstractEngine.activateExceptionPage(AbstractEngine.java:480)
	at org.apache.tapestry.engine.AbstractEngine.service(AbstractEngine.java:914)
	at org.apache.tapestry.ApplicationServlet.doService(ApplicationServlet.java:197)
	at org.apache.tapestry.ApplicationServlet.doPost(ApplicationServlet.java:326)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:810)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:252)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173)
	at inpowered.framework.filter.RequestControlFilter.doFilter(RequestControlFilter.java:149)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:202)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173)
	at org.jboss.web.tomcat.filters.ReplyHeaderFilter.doFilter(ReplyHeaderFilter.java:81)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:202)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:178)
	at org.jboss.web.tomcat.security.CustomPrincipalValve.invoke(CustomPrincipalValve.java:39)
	at org.jboss.web.tomcat.security.SecurityAssociationValve.invoke(SecurityAssociationValve.java:159)
	at org.jboss.web.tomcat.security.JaccContextValve.invoke(JaccContextValve.java:59)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:126)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:105)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:107)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:148)
	at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:856)
	at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.processConnection(Http11Protocol.java:744)
	at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:527)
	at org.apache.tomcat.util.net.MasterSlaveWorkerThread.run(MasterSlaveWorkerThread.java:112)
	at java.lang.Thread.run(Thread.java:534)
2010-02-15 15:43:18,367 ERROR [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost].[/IRIS].[inpowered]] Servlet.service() for servlet inpowered threw exception
org.apache.tapestry.PageRedirectException: index
	at inpowered.common.tapestry.BaseInpoweredPage.needToLogin(BaseInpoweredPage.java:103)
	at inpowered.common.tapestry.BaseInpoweredPage.pageBeginRender(BaseInpoweredPage.java:90)
	at org.apache.tapestry.AbstractPage.firePageBeginRender(AbstractPage.java:463)
	at org.apache.tapestry.AbstractPage.renderPage(AbstractPage.java:293)
	at org.apache.tapestry.engine.RequestCycle.renderPage(RequestCycle.java:371)
	at org.apache.tapestry.engine.AbstractEngine.renderResponse(AbstractEngine.java:732)
	at org.apache.tapestry.engine.AbstractEngine.activateExceptionPage(AbstractEngine.java:459)
	at org.apache.tapestry.engine.AbstractEngine.service(AbstractEngine.java:914)
	at org.apache.tapestry.ApplicationServlet.doService(ApplicationServlet.java:197)
	at org.apache.tapestry.ApplicationServlet.doPost(ApplicationServlet.java:326)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:810)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:252)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173)
	at inpowered.framework.filter.RequestControlFilter.doFilter(RequestControlFilter.java:149)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:202)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173)
	at org.jboss.web.tomcat.filters.ReplyHeaderFilter.doFilter(ReplyHeaderFilter.java:81)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:202)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:178)
	at org.jboss.web.tomcat.security.CustomPrincipalValve.invoke(CustomPrincipalValve.java:39)
	at org.jboss.web.tomcat.security.SecurityAssociationValve.invoke(SecurityAssociationValve.java:159)
	at org.jboss.web.tomcat.security.JaccContextValve.invoke(JaccContextValve.java:59)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:126)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:105)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:107)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:148)
	at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:856)
	at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.processConnection(Http11Protocol.java:744)
	at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:527)
	at org.apache.tomcat.util.net.MasterSlaveWorkerThread.run(MasterSlaveWorkerThread.java:112)
	at java.lang.Thread.run(Thread.java:534)
2010-02-15 20:30:24,869 INFO  [org.apache.catalina.startup.Embedded] Catalina naming disabled
2010-02-15 20:30:26,082 INFO  [org.apache.coyote.http11.Http11Protocol] Initializing Coyote HTTP/1.1 on http-0.0.0.0-8001
2010-02-15 20:30:26,085 INFO  [org.apache.catalina.startup.Catalina] Initialization processed in 1102 ms
2010-02-15 20:30:26,092 INFO  [org.apache.catalina.core.StandardEngine] Starting Servlet Engine: Apache Tomcat/5.5
2010-02-15 20:30:26,155 INFO  [org.apache.catalina.core.StandardHost] XML validation disabled
2010-02-15 20:30:26,201 INFO  [org.apache.catalina.startup.Catalina] Server startup in 116 ms

We can see that our Application server is running on 3:43PM and no logs were generated 'till 8:30PM.
	3:43PM (An Exception incurred)
	<no logs... server is still active>
	8:30PM (The server restarted...)
	
As stated within this link http://logging.apache.org/log4j/1.2/faq.html#1.2ulk
We have a hinch that our application has the similar scenario wherein Log4j is currently being thrown a bulk number of things to log that resulted into a scenario that it wasn't able to write any, during those timeline.

Can you provide us with external links, probable explanation why this had happened, things to consider, things to modify or anything that could help us avoid, resolve or eliminate this?


Thanks in advance,

Frederick Gaetos