0001407: log rotation deadlock

Viewing Issue Simple Details [ Jump to Notes ]

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

Status

closed

Product Version

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.

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.