Log4j 1.2.17+Java8u162日志滚动异常问题求助

我之前也碰到过类似的自定义Log4j Appender因为系统时间回退引发的滚动失效问题，结合你提供的代码、配置和生产场景，咱们来拆解下根因和可行的修复方案：

问题根因分析 #

1. 系统时间回退导致nextCheck陷入"未来时间死锁" #

你的自定义Appender里，nextCheck是用来标记下一次触发时间滚动的时间戳，只有当当前时间n >= nextCheck时，才会重新计算nextCheck并执行滚动。当系统时间从5月10日被回退到3月10日时：

• 当前时间n远小于原本的nextCheck（5月11日0点），所以完全跳过时间滚动的分支
• 即使之后把时间改回5月10日，n依然小于nextCheck（5月11日0点），还是不会触发滚动逻辑
• 等到5月11日0点时，理论上应该触发滚动，但此时因为之前文件已经达到最大尺寸，reachedMaxSize被设为true，即使执行了rollOver()，也可能因为文件时间戳异常（原文件时间是3月10日）导致滚动失败，或者reachedMaxSize没有正确重置，后续日志依然只会输出到stdout。

2. reachedMaxSize状态未正确重置，导致日志永久转向stdout #

当文件达到最大尺寸后，reachedMaxSize被设置为true，之后所有日志都会输出到System.out而不是文件。这个状态只有在时间触发的滚动分支里才会被重置为false，但因为nextCheck一直卡在未来时间点，这个重置逻辑根本不会执行。哪怕之后文件被手动滚动或者因为其他原因变小，Appender也不会再写入文件，直到重启系统。

修复方案 #

1. 新增系统时间回退的检测与修复逻辑 #

在subAppend方法开头，先检查当前时间是否小于nextCheck（说明时间被回退过），如果是，立刻重置nextCheck和reachedMaxSize：

long n = System.currentTimeMillis();
// 新增：处理系统时间回退的情况，避免nextCheck卡在未来时间
if (n < nextCheck) {
    now.setTime(n);
    nextCheck = rc.getNextCheckMillis(now);
    reachedMaxSize = false; // 同步重置大小标记
}
// 原有的时间触发滚动逻辑
if (n >= nextCheck) {
    now.setTime(n);
    nextCheck = rc.getNextCheckMillis(now);
    rollOver();
    // 滚动后重新检查文件大小，避免误设标记
    File newLogFile = new File(getFile());
    reachedMaxSize = newLogFile.length() > maxFileSize;
} else {
    File f = new File(getFile());
    // 新增：如果文件被手动删除/滚动，自动重置reachedMaxSize
    if (!f.exists() || f.length() == 0) {
        reachedMaxSize = false;
    }
    // 原有的文件大小检查逻辑
    if (!reachedMaxSize && f.length() > maxFileSize) {
        LoggingEvent exeededEvent = new LoggingEvent(
            getClass().getName(), 
            Logger.getLogger(getClass().getName()), 
            Priority.ERROR, 
            "Maximum log file size has been reached ("+maxFileSize/1024+"KB)", 
            null
        );
        super.subAppend(exeededEvent);
        reachedMaxSize = true;
    }
    // 日志写入逻辑
    if (!reachedMaxSize) {
        super.subAppend(event);
    } else {
        System.out.println(event.getRenderedMessage());
    }
}

2. 优化rollOver()方法的时间处理 #

确保rollOver()生成备份文件时，使用当前系统时间而不是日志文件的最后修改时间，避免因为文件时间戳异常导致备份文件名错误或滚动失败。比如在生成备份文件名时，直接用new Date()而不是f.lastModified()。

3. 测试环境复现方法（解决无法复现的问题） #

要在测试环境复现这个场景，可以按以下步骤操作：

• 启动应用，让日志正常输出一段时间，确保nextCheck被设置为第二天0点
• 修改系统时间到3个月前，继续生成日志直到文件达到maxFileSize，触发reachedMaxSize=true
• 改回系统时间到当前时间，观察日志是否停止写入文件，只输出到控制台
• 等待到原本的nextCheck时间（比如第二天0点），看是否能自动恢复滚动

这样就能完整复现生产环境的问题，验证修复效果。

内容的提问来源于stack exchange，提问作者gari