Bug 53392

Summary: Rolling file cannot be written, suspecting infinite loop with log4j 1.2.16
Product: Log4j - Now in Jira Reporter: unger
Component: OtherAssignee: log4j-dev <log4j-dev>
Status: NEW ---    
Severity: major    
Priority: P2    
Version: unspecified   
Target Milestone: ---   
Hardware: PC   
OS: All   

Description unger 2012-06-11 09:43:15 UTC
running my application continuously, the log file was not renamed properly for one day (2 days ago). Log4j tried to write this file again (altough now it's 2 days after), and seem to go into an infinite loop.


11:19:17,902  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> 2012-06-11 11:19:17.804:WARN::handle failed
java.io.IOException: Closed
	at org.mortbay.jetty.AbstractGenerator$Output.write(AbstractGenerator.java:632)
	at org.mortbay.jetty.AbstractGenerator$Output.write(AbstractGenerator.java:580)
	at org.mortbay.util.ByteArrayISO8859Writer.writeTo(ByteArrayISO8859Writer.java:103)
	at org.mortbay.jetty.Response.sendError(Response.java:317)
	at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:559)
	at org.mortbay.jetty.HttpConnection$RequestHandler.content(HttpConnection.java:945)
	at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:756)
	at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:212)
	at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:404)
	at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:410)
	at org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:582)
11:19:17,959  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to rename [./logs/andromas.log] to [./logs/andromas.log.2012-06-08].
11:19:18,044  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:17,960  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to rename [./logs/andromas.log] to [./logs/andromas.log.2012-06-08].
].
11:19:18,045  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,045  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:17,960  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to rename [./logs/andromas.log] to [./logs/andromas.log.2012-06-08].
].
].
11:19:18,046  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,046  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,045  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:17,960  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to rename [./logs/andromas.log] to [./logs/andromas.log.2012-06-08].
].
].
].
11:19:18,047  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,046  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,046  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,045  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:17,960  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to rename [./logs/andromas.log] to [./logs/andromas.log.2012-06-08].
].
].
].
].
11:19:18,047  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,047  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,046  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,046  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,045  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:17,960  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to rename [./logs/andromas.log] to [./logs/andromas.log.2012-06-08].
].
].
].
].
].
11:19:18,048  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,048  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,047  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,046  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,046  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,045  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:17,960  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to rename [./logs/andromas.log] to [./logs/andromas.log.2012-06-08].
].
].
].
].
].
].
11:19:18,049  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,049  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,048  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,047  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,046  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,046  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,045  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:17,960  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to rename [./logs/andromas.log] to [./logs/andromas.log.2012-06-08].
].
].
].
].
].
].
].
11:19:18,051  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,050  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,049  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,048  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,047  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,046  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,046  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,045  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:17,960  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to rename [./logs/andromas.log] to [./logs/andromas.log.2012-06-08].
].
].
].
].
].
].
].
].
11:19:18,052  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,051  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,050  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,049  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,048  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,047  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,046  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,046  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,045  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:17,960  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to rename [./logs/andromas.log] to [./logs/andromas.log.2012-06-08].
].
].
].
].
].
].
].
].
].
11:19:18,053  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,052  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,051  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,050  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,049  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,048  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,047  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,046  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,046  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,045  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:17,960  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to rename [./logs/andromas.log] to [./logs/andromas.log.2012-06-08].
].
].
].
].
].
].
].
].
].
].
11:19:18,054  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,054  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,052  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,051  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,050  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,049  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,048  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,047  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,046  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,046  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,045  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:17,960  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to rename [./logs/andromas.log] to [./logs/andromas.log.2012-06-08].
].
].
].
].
].
].
].
].
].
].
].
11:19:18,056  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,055  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,054  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,052  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,051  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,050  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,049  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,048  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,047  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,046  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,046  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:18,045  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to write [11:19:17,960  INFO com.andromas.bgp.StdOutErrLog$1 23 <14134931@qtp-5525185-28> log4j:ERROR Failed to rename [./logs/andromas.log] to [./logs/andromas.log.2012-06-08].
].
].
].
].
].
].
].
].
].
].
Comment 1 grobmeier 2012-06-11 11:29:43 UTC
What operating system are you using? Are the file permissions set correctly?

Thanks!
Comment 2 unger 2012-06-11 13:19:22 UTC
File permissions are ok (it rolled the file the previous day correctly, but not that day).
It did well the day after again.
Platform is Windows7