0004821: daily rollover task blocked some times

Viewing Issue Simple Details [ Jump to Notes ]

[ View Advanced ] [ Issue History ] [ Print ]

Category

Severity

Reproducibility

Date Submitted

Last Update

0004821

[Resin]

block

sometimes

10-22-11 08:08

06-11-12 14:52

Reporter

imxylz

View Status

public

Assigned To

ferg

Priority

normal

Resolution

fixed

Status

closed

Product Version

3.1.11

Summary

0004821: daily rollover task blocked some times

Description

"http--8080-545$1171904446" daemon prio=10 tid=0x00002aaab833c800 nid=0x7e08 waiting for monitor entry [0x000000007c27f000]
   java.lang.Thread.State: BLOCKED (on object monitor)
    at com.caucho.log.StreamHandler.publish(StreamHandler.java:107)
    - waiting to lock <0x0000000711d5ee58> (a com.caucho.vfs.WriteStream)
    at com.caucho.log.SubHandler.publish(SubHandler.java:60)
    at com.caucho.log.EnvironmentLogger.log(EnvironmentLogger.java:331)
    at java.util.logging.Logger.doLog(Logger.java:503)
    at java.util.logging.Logger.log(Logger.java:526)
    at com.caucho.server.webapp.ErrorPageManager.sendServletError(ErrorPageManager.java:329)
    at com.caucho.server.http.HttpRequest.handleRequest(HttpRequest.java:288)
    at com.caucho.server.port.TcpConnection.run(TcpConnection.java:682)
    - locked <0x000000071207c708> (a java.lang.Object)
    at com.caucho.util.ThreadPool$Item.runTasks(ThreadPool.java:743)
    at com.caucho.util.ThreadPool$Item.run(ThreadPool.java:662)
    at java.lang.Thread.run(Thread.java:662)

"http--8080-46$437830282" daemon prio=10 tid=0x00002aaab87f6000 nid=0x7be3 in Object.wait() [0x000000005a967000]
   java.lang.Thread.State: TIMED_WAITING (on object monitor)
    at java.lang.Object.wait(Native Method)
    at com.caucho.util.ThreadPool.schedule(ThreadPool.java:529)
    - locked <0x00000007118d1ca8> (a java.lang.Object)
    at com.caucho.util.ThreadPool.startPriority(ThreadPool.java:418)
    at com.caucho.log.AbstractRolloverLog.rolloverLog(AbstractRolloverLog.java:457)
    at com.caucho.log.AbstractRolloverLog.rollover(AbstractRolloverLog.java:346)
    at com.caucho.log.RotateStream.write(RotateStream.java:243)
    at com.caucho.vfs.WriteStream.flush(WriteStream.java:355)
    at com.caucho.log.EnvironmentStream.flush(EnvironmentStream.java:170)
    - locked <0x0000000711d703e8> (a com.caucho.vfs.WriteStream)
    at com.caucho.vfs.WriteStream.flush(WriteStream.java:360)
    at java.io.PrintStream.write(PrintStream.java:432)
    - locked <0x00000007118bcf30> (a java.io.PrintStream)
    at com.caucho.vfs.StdoutStream.write(StdoutStream.java:80)
    at com.caucho.vfs.WriteStream.flush(WriteStream.java:355)
    at com.caucho.log.AbstractRolloverLog.flush(AbstractRolloverLog.java:394)
    - locked <0x0000000711d5dd10> (a com.caucho.log.AbstractRolloverLog)
    at com.caucho.log.RotateStream.flush(RotateStream.java:264)
    at com.caucho.vfs.WriteStream.flush(WriteStream.java:360)
    at com.caucho.log.TimestampFilter.flush(TimestampFilter.java:237)
    at com.caucho.vfs.WriteStream.flush(WriteStream.java:360)
    at com.caucho.log.StreamHandler.publish(StreamHandler.java:122)
    - locked <0x0000000711d5ee58> (a com.caucho.vfs.WriteStream)
    at com.caucho.log.SubHandler.publish(SubHandler.java:60)
    at com.caucho.log.EnvironmentLogger.log(EnvironmentLogger.java:331)
    at java.util.logging.Logger.doLog(Logger.java:503)
    at java.util.logging.Logger.log(Logger.java:526)
    at com.caucho.server.webapp.ErrorPageManager.sendServletError(ErrorPageManager.java:329)
    at com.caucho.server.http.HttpRequest.handleRequest(HttpRequest.java:288)
    at com.caucho.server.port.TcpConnection.run(TcpConnection.java:682)
    - locked <0x000000071253d290> (a java.lang.Object)
    at com.caucho.util.ThreadPool$Item.runTasks(ThreadPool.java:743)
    at com.caucho.util.ThreadPool$Item.run(ThreadPool.java:662)
    at java.lang.Thread.run(Thread.java:662)

Additional Information

log configuration:

<stderr-log path='/opt/logs/resin/stderr-person.log' rollover-period="1D" archive-format="stderr-person.log.%Y%m%d" />
<stdout-log path='/opt/logs/resin/stdout-person.log' rollover-period="1D" archive-format="stdout-person.log.%Y%m%d"/>

jvm configuration:
<jvm-arg>-Xmx4g</jvm-arg>
<jvm-arg>-Xms4g</jvm-arg>
<jvm-arg>-Xss1m</jvm-arg>
<jvm-arg>-XX:NewSize=400m</jvm-arg>
<jvm-arg>-XX:PermSize=80m</jvm-arg>
<jvm-arg>-XX:MaxPermSize=256m</jvm-arg>
<jvm-arg>-XX:+UseConcMarkSweepGC</jvm-arg>
<jvm-arg>-XX:CMSInitiatingOccupancyFraction=70</jvm-arg>
<jvm-arg>-Dcom.sun.management.jmxremote</jvm-arg>
<jvm-arg>-Xloggc:/opt/logs/resin/jvm-person.log</jvm-arg>

We have changed '-XX:NewSize=400m' to 1024m.

Attached Files

suc-person.20111014.dump [^] (32,248 bytes) 10-22-11 08:09

Relationships

Notes
(0005575) imxylz 10-22-11 08:12	It can be blocked for some minutes or some hours. All requests were blocked.

Issue History
Date Modified	Username	Field	Change
10-22-11 08:09	imxylz	New Issue
10-22-11 08:09	imxylz	File Added: suc-person.20111014.dump
10-22-11 08:12	imxylz	Note Added: 0005575
06-11-12 14:52	ferg	Assigned To	=> ferg
06-11-12 14:52	ferg	Status	new => closed
06-11-12 14:52	ferg	Resolution	open => fixed
06-11-12 14:52	ferg	Fixed in Version	=> 4.0.28

Mantis 1.0.0rc3[^]

30 total queries executed.
26 unique queries executed.