0004821: daily rollover task blocked some times

Viewing Issue Advanced Details [ Jump to Notes ]

[ View Simple ] [ 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

Platform

Status

closed

Projection

none

OS Version

ETA

none

Fixed in Version

4.0.28

Product Version

3.1.11

Product Build

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)

Steps To Reproduce

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.