Spring Boot 应用程序意外关闭并记录问题答案

【问题标题】：Spring boot application shutting down unexpectedly and log issuesSpring Boot 应用程序意外关闭并记录问题
【发布时间】：2018-12-23 00:26:51
【问题描述】：

我有一个使用 spring boot 1.5.6.RELEASE 开发的网络应用程序。该应用程序目前在生产服务用户群约 3000 名用户，同时在两台服务器上运行。

我的问题是当我关闭新版本的应用程序并有时在运行一小时左右后启动新版本时，应用程序会自动关闭。我可以在日志中看到关闭挂钩的日志，但没有其他错误。

2018-07-12 05:46:40.100 DEBUG --- [Thread-7] l.d.o.w.OMApplication : !!!!!!!!!!! application shutdown !!!!!!!!!!!

当我再次启动实例时，它会一直运行，直到我愿意随时关闭，而不会在高负载下出现任何问题。我通过向应用程序发送终止信号并等待进程存在（正常关闭）来关闭应用程序。

我试图找出问题所在，在实际问题出现之前，我认为我的日志有问题。我有针对业务需求的自定义日志配置。即使我已配置为记录错误级别，我的错误日志也无法正常工作并且在错误日志中，我可以看到所有其他日志都在那里打印。我的配置如下。

```

<property name="LOG_DIR" value="logs/om"/>
<property name="LOG_FILE" value="${LOG_FILE:-${LOG_PATH:-${LOG_TEMP:-${java.io.tmpdir:-/tmp}}}/spring.log}"/>
<include resource="org/springframework/boot/logging/logback/defaults.xml" />
<include resource="org/springframework/boot/logging/logback/console-appender.xml" />

<appender name="auditLogAppender" class="ch.qos.logback.core.rolling.RollingFileAppender">
    <file>${LOG_DIR}/audit.log</file>
    <rollingPolicy class="ch.qos.logback.core.rolling.TimeBasedRollingPolicy">
        <fileNamePattern>${LOG_DIR}/audit-%d{yyyy-MM-dd}.log</fileNamePattern>
    </rollingPolicy>
    <encoder>
        <pattern>%d{yyyy-MM-dd HH:mm:ss.SSS} %5p %mdc --- [%t] %C{1} : %m%n</pattern>
    </encoder>
</appender>

<!--<property name="LOG_FILE" value="${LOG_FILE:-${LOG_PATH:-${LOG_TEMP:-${java.io.tmpdir:-/tmp}}}/all.log}"/>-->
<!--<include resource="org/springframework/boot/logging/logback/base.xml"/>-->

<appender name="trace" class="ch.qos.logback.core.rolling.RollingFileAppender">
    <file>${LOG_DIR}/request-trace.log</file>
    <rollingPolicy class="ch.qos.logback.core.rolling.TimeBasedRollingPolicy">
        <fileNamePattern>${LOG_DIR}/request-trace-%d{yyyy-MM-dd}.log.gz</fileNamePattern>
    </rollingPolicy>
    <encoder>
        <pattern>%d{yyyy-MM-dd HH:mm:ss.SSS} %5p %mdc --- [%t] %-40.40c{1.} : %m%n</pattern>
    </encoder>
</appender>


<appender name="REPOSITORY_PERF" class="ch.qos.logback.core.rolling.RollingFileAppender">
    <file>${LOG_DIR}/repository-perf.log</file>
    <rollingPolicy class="ch.qos.logback.core.rolling.TimeBasedRollingPolicy">
        <fileNamePattern>${LOG_DIR}/repository-perf-%d{yyyy-MM-dd}.log.gz</fileNamePattern>
    </rollingPolicy>
    <encoder>
        <pattern>%d{yyyy-MM-dd HH:mm:ss.SSS} %5p %mdc --- [%t] %C{1} : %m%n</pattern>
    </encoder>
</appender>
<appender name="CONTROLLER_PERF" class="ch.qos.logback.core.rolling.RollingFileAppender">
    <file>${LOG_DIR}/controller-perf.log</file>
    <rollingPolicy class="ch.qos.logback.core.rolling.TimeBasedRollingPolicy">
        <fileNamePattern>${LOG_DIR}/controller-perf-%d{yyyy-MM-dd}.log.gz</fileNamePattern>
    </rollingPolicy>
    <encoder>
        <pattern>%d{yyyy-MM-dd HH:mm:ss.SSS} %5p %mdc --- [%t] %C{1} : %m%n</pattern>
    </encoder>
</appender>
<appender name="INTEGRATION_PERF" class="ch.qos.logback.core.rolling.RollingFileAppender">
    <file>${LOG_DIR}/integration-perf.log</file>
    <rollingPolicy class="ch.qos.logback.core.rolling.TimeBasedRollingPolicy">
        <fileNamePattern>${LOG_DIR}/integration-perf-%d{yyyy-MM-dd}.log.gz</fileNamePattern>
    </rollingPolicy>
    <encoder>
        <pattern>%d{yyyy-MM-dd HH:mm:ss.SSS} %5p %mdc --- [%t] %C{1} : %m%n</pattern>
    </encoder>
</appender>
<appender name="SERVICE_PERF" class="ch.qos.logback.core.rolling.RollingFileAppender">
    <file>${LOG_DIR}/service-perf.log</file>
    <rollingPolicy class="ch.qos.logback.core.rolling.TimeBasedRollingPolicy">
        <fileNamePattern>${LOG_DIR}/service-perf-%d{yyyy-MM-dd}.log.gz</fileNamePattern>
    </rollingPolicy>
    <encoder>
        <pattern>%d{yyyy-MM-dd HH:mm:ss.SSS} %5p %mdc --- [%t] %C{1} : %m%n</pattern>
    </encoder>
</appender>

<appender name="ERROR_LOG_APPENDER" class="ch.qos.logback.core.rolling.RollingFileAppender">
    <file>${LOG_DIR}/error.log</file>
    <rollingPolicy class="ch.qos.logback.core.rolling.TimeBasedRollingPolicy">
        <fileNamePattern>${LOG_DIR}/error-%d{yyyy-MM-dd}.log.gz</fileNamePattern>
    </rollingPolicy>
    <encoder>
        <pattern>%d{yyyy-MM-dd HH:mm:ss.SSS} %5p %mdc --- [%t] %C{1} : %m%n</pattern>
    </encoder>
</appender>

<!--spring log appender-->

<appender name="SPRING_LOG_APPENDER" class="ch.qos.logback.core.rolling.RollingFileAppender">
    <file>${LOG_DIR}/spring.log</file>
    <rollingPolicy class="ch.qos.logback.core.rolling.TimeBasedRollingPolicy">
        <fileNamePattern>${LOG_DIR}/spring-%d{yyyy-MM-dd}.log</fileNamePattern>
    </rollingPolicy>
    <encoder>
        <pattern>%d{yyyy-MM-dd HH:mm:ss.SSS} %5p %mdc --- [%t] %C{1} : %m%n</pattern>
    </encoder>
</appender>

<appender name="ORDER_LOG_APPENDER" class="ch.qos.logback.core.rolling.RollingFileAppender">
    <file>${LOG_DIR}/order.log</file>
    <rollingPolicy class="ch.qos.logback.core.rolling.TimeBasedRollingPolicy">
        <fileNamePattern>${LOG_DIR}/order-%d{yyyy-MM-dd}.log</fileNamePattern>
    </rollingPolicy>
    <encoder>
        <pattern>%d{yyyy-MM-dd HH:mm:ss.SSS} %5p %mdc --- [%t] %C{1} : %m%n</pattern>
    </encoder>
</appender>

<appender name="TRACKED_APPENDER" class="ch.qos.logback.core.rolling.RollingFileAppender">
    <file>${LOG_DIR}/tracked.log</file>
    <rollingPolicy class="ch.qos.logback.core.rolling.TimeBasedRollingPolicy">
        <fileNamePattern>${LOG_DIR}/tracked-%d{yyyy-MM-dd}.log</fileNamePattern>
    </rollingPolicy>
    <encoder>
        <pattern>%d{yyyy-MM-dd HH:mm:ss.SSS} %5p %mdc --- [%t] %C{1} : %m%n</pattern>
    </encoder>
</appender>

<logger name="lk.ravinda.om" level="debug">
    <appender-ref ref="SPRING_LOG_APPENDER"/>
</logger>

<logger name="org.springframework" level="debug">
    <appender-ref ref="SPRING_LOG_APPENDER"/>
</logger>

<logger name="org.apache" level="error">
    <appender-ref ref="SPRING_LOG_APPENDER"/>
</logger>

<logger name="lk.ravinda.crm.repositoryutil.aspects" level="off">
    <appender-ref ref="SPRING_LOG_APPENDER"/>
</logger>

<logger name="lk.ravinda.om.crm.impl" level="INFO">
    <appender-ref ref="ORDER_LOG_APPENDER"/>
</logger>
<!--end spring log appender config-->


<logger name="SERVICE" level="debug">
    <appender-ref ref="SERVICE_PERF"/>
</logger>
<logger name="CONTROLLER" level="debug">
    <appender-ref ref="CONTROLLER_PERF"/>
</logger>
<logger name="INTEGRATION" level="debug">
    <appender-ref ref="INTEGRATION_PERF"/>
</logger>
<logger name="REPOSITORY" level="debug">
    <appender-ref ref="REPOSITORY_PERF"/>
</logger>

<logger name="auditLog" level="debug">
    <appender-ref ref="auditLogAppender"/>
</logger>

<logger name="TRACKED_LOGGER" level="debug">
    <appender-ref ref="TRACKED_APPENDER"/>
</logger>

<logger name="org.springframework" level="error">
    <appender-ref ref="ERROR_LOG_APPENDER"/>
</logger>

<logger name="com" level="error">
    <appender-ref ref="ERROR_LOG_APPENDER"/>
</logger>

<logger name="lk.ravinda" level="error">
    <appender-ref ref="ERROR_LOG_APPENDER"/>
</logger>

<logger name="trace" level="debug">
    <appender-ref ref="trace"/>
</logger>

<root level="INFO">
    <appender-ref ref="CONSOLE" />
</root>

```

我怀疑在 error.log 和 spring.log 中没有打印与 spring 相关的错误。

感谢任何有关此问题的 cmets 想法，以发现应用程序关闭的问题。

【问题讨论】：

您是直接使用fat jar 还是将您的应用程序部署在像tomcat 这样的网络服务器上？您在控制台的信息级别设置 ROOT 记录器。你在这个日志中得到了什么？
如果您的操作系统是 Linux，则值得查看系统日志（journactl 或 /var/log/messages）。
@wargre 我正在使用由 spring boot 构建的 fat jar。

标签： spring-boot logging shutdown-hook application-shutdown

【解决方案1】：

至于未出现的日志记录 - 看起来您必须为同一类别“org.springframework”指定附加程序的元素，我认为 Log4J 不会将两者结合起来。您可能应该为该类别拥有一个包含两个附加程序的单个元素，然后在每个附加程序中配置“阈值”属性，以便一个仅包含错误级别消息，而另一个包含调试级别的所有内容...

我在这里看到了这个： http://ibswings.blogspot.com/2009/03/log4j-configuration-controlling-logging.html

【讨论】：

【解决方案2】：

在 Spring Boot 中使用预先配置的 log4j 配置尝试一次，而不是自定义日志。在 application.properties 文件中使用： logging.level.{应用程序包名称}=ERROR

{应用程序包名称}，例如 com.xyz.myapplication。存在主类的包。希望对你有帮助

【讨论】：