0001407: log rotation deadlock

Viewing Issue Advanced Details [ Jump to Notes ]

[ View Simple ] [ Issue History ] [ Print ]

Category

Severity

Reproducibility

Date Submitted

Last Update

0001407

[Resin]

minor

always

10-12-06 09:41

10-12-06 09:42

Reporter

ferg

View Status

public

Assigned To

ferg

Priority

normal

Resolution

fixed

Platform

Status

closed

Projection

none

OS Version

ETA

none

Fixed in Version

3.0.19

Product Version

Product Build

Summary

0001407: log rotation deadlock

Description

(rep by sksamuel)

Found one Java-level deadlock:
=============================
"resin-2140":
  waiting to lock monitor 0x0975151c (object 0x834aff58, a com.caucho.vfs.WriteStream),
  which is held by "resin-tcp-connection-*:80-570"
"resin-tcp-connection-*:80-570":
  waiting to lock monitor 0x094d1a8c (object 0x833d7c48, a java.io.PrintStream),
  which is held by "resin-2140"

Java stack information for the threads listed above:
===================================================
"resin-2140":
    at com.caucho.log.StreamHandler.publish(StreamHandler.java:112)
    - waiting to lock <0x834aff58> (a com.caucho.vfs.WriteStream)
    at com.caucho.log.SubHandler.publish(SubHandler.java:73)
    at java.util.logging.Logger.log(Logger.java:428)
    at java.util.logging.Logger.doLog(Logger.java:450)
    at java.util.logging.Logger.log(Logger.java:473)
    at java.util.logging.Logger.info(Logger.java:992)
    at com.caucho.vfs.AbstractRolloverLog.movePathToArchive(AbstractRolloverLog.java:431)
    at com.caucho.vfs.AbstractRolloverLog.rolloverLog(AbstractRolloverLog.java:339)
    at com.caucho.vfs.AbstractRolloverLog.rollover(AbstractRolloverLog.java:289)
    at com.caucho.vfs.RotateStream.write(RotateStream.java:247)
    at com.caucho.vfs.WriteStream.flush(WriteStream.java:335)
    at com.caucho.vfs.EnvironmentStream.flush(EnvironmentStream.java:166)
    - locked <0x8432f488> (a com.caucho.vfs.WriteStream)
    at com.caucho.vfs.WriteStream.flush(WriteStream.java:339)
    at java.io.PrintStream.write(PrintStream.java:414)
    - locked <0x833d7c48> (a java.io.PrintStream)
    at sun.nio.cs.StreamEncoder$CharsetSE.writeBytes(StreamEncoder.java:336)
    at sun.nio.cs.StreamEncoder$CharsetSE.implFlushBuffer(StreamEncoder.java:404)
    at sun.nio.cs.StreamEncoder.flushBuffer(StreamEncoder.java:115)
    - locked <0x833ec2e8> (a java.io.OutputStreamWriter)
    at java.io.OutputStreamWriter.flushBuffer(OutputStreamWriter.java:169)
    at java.io.PrintStream.write(PrintStream.java:459)
    - locked <0x833d7c48> (a java.io.PrintStream)
    at java.io.PrintStream.print(PrintStream.java:602)
    at java.io.PrintStream.println(PrintStream.java:739)
    - locked <0x833d7c48> (a java.io.PrintStream)
    at org.foo.FeedRunner.run(FeedRunner.java:23)
    at org.foo.CronHourlyServlet.doGet(CronHourlyServlet.java:42)
    at javax.servlet.http.HttpServlet.service(HttpServlet.java:115)
    at javax.servlet.http.HttpServlet.service(HttpServlet.java:92)
    at com.caucho.server.dispatch.ServletFilterChain.doFilter(ServletFilterChain.java:106)
    at com.caucho.server.dispatch.ServletConfigImpl.handleAlarm(ServletConfigImpl.java:502)
    at com.caucho.util.Alarm.handleAlarm(Alarm.java:310)
    at com.caucho.util.Alarm.run(Alarm.java:280)
    at com.caucho.util.ThreadPool.runTasks(ThreadPool.java:490)
    at com.caucho.util.ThreadPool.run(ThreadPool.java:423)
    at java.lang.Thread.run(Thread.java:595)
"resin-tcp-connection-*:80-570":
    at java.io.PrintStream.write(PrintStream.java:410)
    - waiting to lock <0x833d7c48> (a java.io.PrintStream)
    at com.caucho.vfs.StdoutStream.write(StdoutStream.java:85)
    at com.caucho.vfs.WriteStream.flush(WriteStream.java:335)
    at com.caucho.vfs.AbstractRolloverLog.flush(AbstractRolloverLog.java:316)
    at com.caucho.vfs.RotateStream.flush(RotateStream.java:268)
    at com.caucho.vfs.WriteStream.flush(WriteStream.java:339)
    at com.caucho.vfs.TimestampFilter.flush(TimestampFilter.java:132)
    at com.caucho.vfs.WriteStream.flush(WriteStream.java:339)
    at com.caucho.log.StreamHandler.publish(StreamHandler.java:128)
    - locked <0x834aff58> (a com.caucho.vfs.WriteStream)
    at com.caucho.log.SubHandler.publish(SubHandler.java:73)
    at java.util.logging.Logger.log(Logger.java:428)
    at java.util.logging.Logger.doLog(Logger.java:450)
    at java.util.logging.Logger.logp(Logger.java:566)
    at com.mysql.jdbc.log.Jdk14Logger.logInternal(Jdk14Logger.java:290)
    at com.mysql.jdbc.log.Jdk14Logger.logInfo(Jdk14Logger.java:182)
    at com.mysql.jdbc.profiler.ProfileEventSink.consumeEvent(ProfileEventSink.java:75)
    at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:1762)
    at com.mysql.jdbc.Connection.execSQL(Connection.java:2988)
    - locked <0x98767918> (a java.lang.Object)
    at com.mysql.jdbc.Connection.configureClientCharacterSet(Connection.java:2333)
    at com.mysql.jdbc.Connection.initializePropsFromServer(Connection.java:3713)
    at com.mysql.jdbc.Connection.createNewIO(Connection.java:2571)
    at com.mysql.jdbc.Connection.<init>(Connection.java:1485)
    at com.mysql.jdbc.NonRegisteringDriver.connect(NonRegisteringDriver.java:266)
    at com.caucho.sql.DriverConfig.createDriverConnection(DriverConfig.java:548)
    at com.caucho.sql.ManagedConnectionImpl.initDriverConnection(ManagedConnectionImpl.java:233)
    at com.caucho.sql.ManagedConnectionImpl.<init>(ManagedConnectionImpl.java:131)
    at com.caucho.sql.ManagedFactoryImpl.createManagedConnection(ManagedFactoryImpl.java:121)
    at com.caucho.jca.ConnectionPool.create(ConnectionPool.java:791)
    at com.caucho.jca.ConnectionPool.allocatePool(ConnectionPool.java:660)
    at com.caucho.jca.ConnectionPool.allocate(ConnectionPool.java:619)
    at com.caucho.jca.ConnectionPool.allocateConnection(ConnectionPool.java:516)
    at com.caucho.sql.DataSourceImpl.getConnection(DataSourceImpl.java:65)
    at com.caucho.sql.DBPool.getConnection(DBPool.java:593)

Found 1 deadlock.

Steps To Reproduce

Additional Information

Attached Files

Relationships

There are no notes attached to this issue.

Issue History
Date Modified	Username	Field	Change
10-12-06 09:41	ferg	New Issue
10-12-06 09:42	ferg	Assigned To	=> ferg
10-12-06 09:42	ferg	Status	new => closed
10-12-06 09:42	ferg	Resolution	open => fixed
10-12-06 09:42	ferg	Fixed in Version	=> 3.0.19

Mantis 1.0.0rc3[^]

26 total queries executed.
24 unique queries executed.