通过日志检查事务是否开启

2016-07-27

如何配置JDBC日志

在检查Spring和SpringMVC配置冲突导致事务无法开启和反射调用事务方法导致事务失效引起的异常中，怎么查原因都查不到，最后在同事的介绍下，帮我配置了下数据库日志，可以看到事务是否真的被开启了

用到的组件

打印日志用的是 SLF4J 和 jdbcdslog 其Maven配置如下

<!--slf4j-->
<dependency>
    <groupId>org.slf4j</groupId>
    <artifactId>slf4j-api</artifactId>
    <version>${slf4j.version}</version>
</dependency>

<dependency>
    <groupId>org.slf4j</groupId>
    <artifactId>jcl-over-slf4j</artifactId>
    <version>${slf4j.version}</version>
    <scope>runtime</scope>
</dependency>

<dependency>
    <groupId>org.slf4j</groupId>
    <artifactId>slf4j-log4j12</artifactId>
    <version>${slf4j.version}</version>
    <scope>runtime</scope>
</dependency>

<dependency>
    <groupId>com.googlecode.usc</groupId>
    <artifactId>jdbcdslog</artifactId>
    <version>1.0.6.2</version>
</dependency>

Slf4j的配置

配置文件为logback.xml 由于不是太熟悉，我采用的是以下配置

<?xml version="1.0" encoding="UTF-8"?>
<configuration>

    <!-- 管控台日志打印，发布生产需注释 -->
    <appender name="STDOUT" class="ch.qos.logback.core.ConsoleAppender">
        <encoder charset="utf-8"> <!-- encoder 可以指定字符集，对于中文输出有意义 -->
            <!-- %.-1level 只显示信息级别的首字母,%-5level 左对齐显示信息级别全称 -->
            <!-- 如需自定义关键字，用 %mdc{键名} 表示,程序中用MDC.put("键名","键值")设置，可动态设置 [%logger:%line]-->
            <Pattern>[%date{yyyy-MM-dd HH:mm:ss}][%thread][%-5level] %c{40} %line --%mdc{client} [%X{TRACE_LOG_ID}] %msg%n</Pattern>
        </encoder>>
    </appender>

    <!-- 外部jar包 日志级别设置 -->
    <logger level="INFO" name="com.ibatis" />
    <logger level="INFO" name="org.springframework"/>
    <logger level="INFO" name="java.sql"/>
    <logger level="INFO" name="org.jdbcdslog"/>


    <!-- 输出到控制台和文件，可定义更多的 Appender -->
    <root level="DEBUG" >
        <appender-ref ref="STDOUT"/>
    </root>

</configuration>

事务状态和非事务状态的日志

这样在创建Sql的时候就可以看到事务日志和非事务日志的区别，由于我DAL层使用的是MyBatis，看到的是以下日志

为了方便追踪最好给日志加上ID，参考这里给日志添加TraceID

事务状态

[2016-07-28 23:17:20][qtp151784151-26][DEBUG] org.mybatis.spring.SqlSessionUtils 120 -- [] Registering transaction synchronization for SqlSession [org.apache.ibatis.session.defaults.DefaultSqlSession@2ac108c2]
......
[2016-07-28 23:17:20][qtp151784151-26][DEBUG] org.mybatis.spring.SqlSessionUtils 163 -- [] Releasing transactional SqlSession [org.apache.ibatis.session.defaults.DefaultSqlSession@2ac108c2]

非事务状态

[2016-07-28 23:20:12][qtp151784151-25][DEBUG] org.mybatis.spring.SqlSessionUtils 140 -- [] SqlSession [org.apache.ibatis.session.defaults.DefaultSqlSession@51bdc159] was not registered for synchronization because synchronization is not active
.......
[2016-07-28 23:20:12][qtp151784151-25][DEBUG] org.mybatis.spring.SqlSessionUtils 168 -- [] Closing non transactional SqlSession [org.apache.ibatis.session.defaults.DefaultSqlSession@51bdc159]