'Category callAppenders synchronization causes java.lang.Thread.State: BLOCKED'

[prev in list] [next in list] [prev in thread] [next in thread] 

List:       log4j-dev
Subject:    Category callAppenders synchronization causes java.lang.Thread.State: BLOCKED
From:       Abhinab <abhinabarana97 () gmail ! com>
Date:       2012-10-31 15:29:13
Message-ID: loom.20121031T162752-377 () post ! gmane ! org
[Download RAW message or body]


Hi,

I'm using Log4j 1.2.16. Last couple of days i'm facing "java.lang.Thread.State: 
BLOCKED (on object monitor)" problem which cause slowness of my application.
I'm using 
1. Log4j 1.2.16  --- RollingFileAppender
2. Jdk 1.6
3. Weblogic 10.3.4

Below is my Thread dump :

"[ACTIVE] ExecuteThread: '57' for queue: 'weblogic.kernel.Default (self-
tuning)'" daemon prio=3 tid=0x00000000066e9000 nid=0xc5 waiting for monitor 
entry [0xfffffd7ff0f7c000]
   java.lang.Thread.State: BLOCKED (on object monitor)
	at org.apache.log4j.Category.callAppenders(Category.java:204)
	- waiting to lock <0x00000000407a4030> (a org.apache.log4j.Logger)
	at org.apache.log4j.Category.forcedLog(Category.java:391)
	at org.apache.log4j.Category.info(Category.java:666)
	at com.crimsonlogic.legal.esi.util.ESIPrint.show(ESIPrint.java:54)
	at 
com.crimsonlogic.legal.esi.submission.ejb.SubmissionFormSLBean.getSubmissionDeta
il(SubmissionFormSLBean.java:226)
	at sun.reflect.GeneratedMethodAccessor235.invoke(Unknown Source)
	at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.jav
a:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at 
com.bea.core.repackaged.springframework.aop.support.AopUtils.invokeJoinpointUsin
gReflection(AopUtils.java:310)
	at 
com.bea.core.repackaged.springframework.aop.framework.ReflectiveMethodInvocation
.invokeJoinpoint(ReflectiveMethodInvocation.java:182)
	at 
com.bea.core.repackaged.springframework.aop.framework.ReflectiveMethodInvocation
.proceed(ReflectiveMethodInvocation.java:149)
	at 
com.bea.core.repackaged.springframework.aop.support.DelegatingIntroductionInterc
eptor.doProceed(DelegatingIntroductionInterceptor.java:131)
	at 
com.bea.core.repackaged.springframework.aop.support.DelegatingIntroductionInterc
eptor.invoke(DelegatingIntroductionInterceptor.java:119)
	at 
com.bea.core.repackaged.springframework.aop.framework.ReflectiveMethodInvocation
.proceed(ReflectiveMethodInvocation.java:171)
	at 
com.bea.core.repackaged.springframework.jee.spi.MethodInvocationVisitorImpl.visi
t(MethodInvocationVisitorImpl.java:37)
	at 
weblogic.ejb.container.injection.EnvironmentInterceptorCallbackImpl.callback(Env
ironmentInterceptorCallbackImpl.java:54)
	at 
com.bea.core.repackaged.springframework.jee.spi.EnvironmentInterceptor.invoke(En
vironmentInterceptor.java:50)
	at 
com.bea.core.repackaged.springframework.aop.framework.ReflectiveMethodInvocation
.proceed(ReflectiveMethodInvocation.java:171)
	at 
com.bea.core.repackaged.springframework.aop.interceptor.ExposeInvocationIntercep
tor.invoke(ExposeInvocationInterceptor.java:89)
	at 
com.bea.core.repackaged.springframework.aop.framework.ReflectiveMethodInvocation
.proceed(ReflectiveMethodInvocation.java:171)
	at 
com.bea.core.repackaged.springframework.aop.support.DelegatingIntroductionInterc
eptor.doProceed(DelegatingIntroductionInterceptor.java:131)
	at 
com.bea.core.repackaged.springframework.aop.support.DelegatingIntroductionInterc
eptor.invoke(DelegatingIntroductionInterceptor.java:119)
	at 
com.bea.core.repackaged.springframework.aop.framework.ReflectiveMethodInvocation
.proceed(ReflectiveMethodInvocation.java:171)
	at 
com.bea.core.repackaged.springframework.aop.framework.JdkDynamicAopProxy.invoke(
JdkDynamicAopProxy.java:204)
	at $Proxy64.getSubmissionDetail(Unknown Source)
	at 
com.crimsonlogic.legal.esi.submission.ejb.SubmissionFormSLBean_w2iutq_EOImpl.__W
L_invoke(Unknown Source)
	at 
weblogic.ejb.container.internal.SessionRemoteMethodInvoker.invoke(SessionRemoteM
ethodInvoker.java:40)
	at 
com.crimsonlogic.legal.esi.submission.ejb.SubmissionFormSLBean_w2iutq_EOImpl.get
SubmissionDetail(Unknown Source)
	at 
com.crimsonlogic.legal.esi.submission.util.SubmissionFormBiz.editSubmission(Subm
issionFormBiz.java:1528)
	at 
com.crimsonlogic.legal.esi.submission.util.SubmissionFormBiz.processRequest(Subm
issionFormBiz.java:4473)
	at 
com.crimsonlogic.legal.esi.submission.servlet.SubmissionServlet.doGet(Submission
Servlet.java:90)
	at 
com.crimsonlogic.legal.esi.submission.servlet.SubmissionServlet.doPost(Submissio
nServlet.java:66)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:727)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:820)
	at 
weblogic.servlet.internal.StubSecurityHelper$ServletServiceAction.run(StubSecuri
tyHelper.java:227)
	at 
weblogic.servlet.internal.StubSecurityHelper.invokeServlet(StubSecurityHelper.ja
va:125)
	at 
weblogic.servlet.internal.ServletStubImpl.execute(ServletStubImpl.java:300)
	at weblogic.servlet.internal.TailFilter.doFilter(TailFilter.java:26)
	at 
weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:56)
	at 
com.crimsonlogic.legal.esi.util.MDCUserServletFilter.doFilter(MDCUserServletFilt
er.java:61)
	at 
weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:56)
	at 
com.aesinfotech.sessiontracker.SessionTrackingFilter.doFilter(SessionTrackingFil
ter.java:198)
	at 
weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:56)
	at 
com.crimsonlogic.legal.esi.useradmin.servlet.UserAdminFilterServlet.doFilter(Use
rAdminFilterServlet.java:178)
	at 
weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:56)
	at 
weblogic.servlet.internal.WebAppServletContext$ServletInvocationAction.wrapRun(W
ebAppServletContext.java:3715)
	at 
weblogic.servlet.internal.WebAppServletContext$ServletInvocationAction.run(WebAp
pServletContext.java:3681)
	at 
weblogic.security.acl.internal.AuthenticatedSubject.doAs(AuthenticatedSubject.ja
va:321)
	at 
weblogic.security.service.SecurityManager.runAs(SecurityManager.java:120)
	at 
weblogic.servlet.internal.WebAppServletContext.securedExecute(WebAppServletConte
xt.java:2277)
	at 
weblogic.servlet.internal.WebAppServletContext.execute(WebAppServletContext.java
:2183)
	at 
weblogic.servlet.internal.ServletRequestImpl.run(ServletRequestImpl.java:1454)
	at weblogic.work.ExecuteThread.execute(ExecuteThread.java:207)
	at weblogic.work.ExecuteThread.run(ExecuteThread.java:176)

"[STANDBY] ExecuteThread: '56' for queue: 'weblogic.kernel.Default (self-
tuning)'" daemon prio=3 tid=0x000000000ab76000 nid=0xc4 in Object.wait() 
[0xfffffd7fefa68000]
   java.lang.Thread.State: WAITING (on object monitor)
	at java.lang.Object.wait(Native Method)
	- waiting on <0x00000000435ab2a8> (a weblogic.work.ExecuteThread)
	at java.lang.Object.wait(Object.java:485)
	at weblogic.work.ExecuteThread.waitForRequest(ExecuteThread.java:160)
	- locked <0x00000000435ab2a8> (a weblogic.work.ExecuteThread)
	at weblogic.work.ExecuteThread.run(ExecuteThread.java:181)

"[ACTIVE] ExecuteThread: '55' for queue: 'weblogic.kernel.Default (self-
tuning)'" daemon prio=3 tid=0x000000000ab74000 nid=0xc3 waiting for monitor 
entry [0xfffffd7ff0b78000]
   java.lang.Thread.State: BLOCKED (on object monitor)
	at org.apache.log4j.Category.callAppenders(Category.java:204)
	- waiting to lock <0x00000000407a4030> (a org.apache.log4j.Logger)
	at org.apache.log4j.Category.forcedLog(Category.java:391)
	at org.apache.log4j.Category.info(Category.java:666)
	at com.crimsonlogic.legal.esi.util.ESIPrint.show(ESIPrint.java:54)
	at 
com.crimsonlogic.legal.esi.submission.util.SubmissionFormBiz.printCertificate(Su
bmissionFormBiz.java:2784)
	at 
com.crimsonlogic.legal.esi.submission.util.SubmissionFormBiz.processRequest(Subm
issionFormBiz.java:4495)
	at 
com.crimsonlogic.legal.esi.submission.servlet.SubmissionServlet.doGet(Submission
Servlet.java:90)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:707)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:820)
	at 
weblogic.servlet.internal.StubSecurityHelper$ServletServiceAction.run(StubSecuri
tyHelper.java:227)
	at 
weblogic.servlet.internal.StubSecurityHelper.invokeServlet(StubSecurityHelper.ja
va:125)
	at 
weblogic.servlet.internal.ServletStubImpl.execute(ServletStubImpl.java:300)
	at weblogic.servlet.internal.TailFilter.doFilter(TailFilter.java:26)
	at 
weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:56)
	at 
com.crimsonlogic.legal.esi.util.MDCUserServletFilter.doFilter(MDCUserServletFilt
er.java:61)
	at 
weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:56)
	at 
com.aesinfotech.sessiontracker.SessionTrackingFilter.doFilter(SessionTrackingFil
ter.java:198)
	at 
weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:56)
	at 
com.crimsonlogic.legal.esi.useradmin.servlet.UserAdminFilterServlet.doFilter(Use
rAdminFilterServlet.java:178)
	at 
weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:56)
	at 
weblogic.servlet.internal.WebAppServletContext$ServletInvocationAction.wrapRun(W
ebAppServletContext.java:3715)
	at 
weblogic.servlet.internal.WebAppServletContext$ServletInvocationAction.run(WebAp
pServletContext.java:3681)
	at 
weblogic.security.acl.internal.AuthenticatedSubject.doAs(AuthenticatedSubject.ja
va:321)
	at 
weblogic.security.service.SecurityManager.runAs(SecurityManager.java:120)
	at 
weblogic.servlet.internal.WebAppServletContext.securedExecute(WebAppServletConte
xt.java:2277)
	at 
weblogic.servlet.internal.WebAppServletContext.execute(WebAppServletContext.java
:2183)
	at 
weblogic.servlet.internal.ServletRequestImpl.run(ServletRequestImpl.java:1454)
	at weblogic.work.ExecuteThread.execute(ExecuteThread.java:207)
	at weblogic.work.ExecuteThread.run(ExecuteThread.java:176)

"[ACTIVE] ExecuteThread: '54' for queue: 'weblogic.kernel.Default (self-
tuning)'" daemon prio=3 tid=0x000000000b37e800 nid=0xc2 waiting for monitor 
entry [0xfffffd7ff0e7c000]
   java.lang.Thread.State: BLOCKED (on object monitor)
	at org.apache.log4j.Category.callAppenders(Category.java:204)
	- waiting to lock <0x00000000407a4030> (a org.apache.log4j.Logger)
	at org.apache.log4j.Category.forcedLog(Category.java:391)
	at org.apache.log4j.Category.info(Category.java:666)
	at com.crimsonlogic.legal.esi.util.ESIPrint.show(ESIPrint.java:54)
	at 
com.crimsonlogic.legal.esi.submission.util.SubmissionFormBiz.processRequest(Subm
issionFormBiz.java:4678)
	at 
com.crimsonlogic.legal.esi.submission.servlet.SubmissionServlet.doGet(Submission
Servlet.java:90)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:707)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:820)
	at 
weblogic.servlet.internal.StubSecurityHelper$ServletServiceAction.run(StubSecuri
tyHelper.java:227)
	at 
weblogic.servlet.internal.StubSecurityHelper.invokeServlet(StubSecurityHelper.ja
va:125)
	at 
weblogic.servlet.internal.ServletStubImpl.execute(ServletStubImpl.java:300)
	at weblogic.servlet.internal.TailFilter.doFilter(TailFilter.java:26)
	at 
weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:56)
	at 
com.crimsonlogic.legal.esi.util.MDCUserServletFilter.doFilter(MDCUserServletFilt
er.java:61)
	at 
weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:56)
	at 
com.aesinfotech.sessiontracker.SessionTrackingFilter.doFilter(SessionTrackingFil
ter.java:198)
	at 
weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:56)
	at 
com.crimsonlogic.legal.esi.useradmin.servlet.UserAdminFilterServlet.doFilter(Use
rAdminFilterServlet.java:178)
	at 
weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:56)
	at 
weblogic.servlet.internal.WebAppServletContext$ServletInvocationAction.wrapRun(W
ebAppServletContext.java:3715)
	at 
weblogic.servlet.internal.WebAppServletContext$ServletInvocationAction.run(WebAp
pServletContext.java:3681)
	at 
weblogic.security.acl.internal.AuthenticatedSubject.doAs(AuthenticatedSubject.ja
va:321)
	at 
weblogic.security.service.SecurityManager.runAs(SecurityManager.java:120)
	at 
weblogic.servlet.internal.WebAppServletContext.securedExecute(WebAppServletConte
xt.java:2277)
	at 
weblogic.servlet.internal.WebAppServletContext.execute(WebAppServletContext.java
:2183)
	at 
weblogic.servlet.internal.ServletRequestImpl.run(ServletRequestImpl.java:1454)
	at weblogic.work.ExecuteThread.execute(ExecuteThread.java:207)
	at weblogic.work.ExecuteThread.run(ExecuteThread.java:176)

"[STANDBY] ExecuteThread: '53' for queue: 'weblogic.kernel.Default (self-
tuning)'" daemon prio=3 tid=0x0000000003f07000 nid=0xc1 in Object.wait() 
[0xfffffd7fefe6c000]
   java.lang.Thread.State: WAITING (on object monitor)
	at java.lang.Object.wait(Native Method)
	- waiting on <0x00000000435adba8> (a weblogic.work.ExecuteThread)
	at java.lang.Object.wait(Object.java:485)
	at weblogic.work.ExecuteThread.waitForRequest(ExecuteThread.java:160)
	- locked <0x00000000435adba8> (a weblogic.work.ExecuteThread)
	at weblogic.work.ExecuteThread.run(ExecuteThread.java:181)



Please help me. Any response will be helpful.

Best Regards,
Abhinab


---------------------------------------------------------------------
To unsubscribe, e-mail: log4j-dev-unsubscribe@logging.apache.org
For additional commands, e-mail: log4j-dev-help@logging.apache.org

[prev in list] [next in list] [prev in thread] [next in thread]
Configure | About | News | Add a list | Sponsored by KoreLogic