上一篇我们介绍了Mybatis中的参数,本篇我们使用JDK Log打印一下Mybatis运行时的日志,看一下Mybatis执行的过程。
这里我选取上一篇的示例进行JDK Log的集成,这里如果您想对上一篇进行详细了解,可以参考:
Mybatis参数(parameterType)https://blog.csdn.net/m1729339749/article/details/132548838
一、配置Mybatis
在mybatis-config.xml文件中配置logImpl
<?xml version="1.0" encoding="UTF-8" ?>
<!DOCTYPE configurationPUBLIC "-//mybatis.org//DTD Config 3.0//EN""http://mybatis.org/dtd/mybatis-3-config.dtd">
<configuration><settings><setting name="logImpl" value="JDK_LOGGING"/></settings><environments default="development"><environment id="development"><transactionManager type="JDBC"/><dataSource type="POOLED"><property name="driver" value="org.gjt.mm.mysql.Driver"/><property name="url" value="jdbc:mysql://localhost:3306/demo?useUnicode=true&useSSL=false&characterEncoding=utf8"/><property name="username" value="root"/><property name="password" value="horse"/></dataSource></environment></environments><mappers><mapper resource="demo/UserInfoMapper.xml" /></mappers>
</configuration>
在配置文件中,我们配置了logImpl,值配置成了JDK_LOGGING,代表的是使用JDK提供的日志系统
二、JDK日志配置
在resources目录下新建logging.properties配置文件
handlers=java.util.logging.ConsoleHandler
.level=FINERjava.util.logging.ConsoleHandler.level=ALL
java.util.logging.ConsoleHandler.formatter=java.util.logging.SimpleFormatter
java.util.logging.SimpleFormatter.format=%1$tY-%1$tm-%1$td %1$tT.%1$tL %4$s %3$s - %5$s%6$s%n
handlers:代表的是日志处理器,java.util.logging.ConsoleHandler处理器用于直接在控制台打印日志
.level:代表的是全局日志级别
java.util.logging.ConsoleHandler.level:设置的是处理器需要处理的日志级别
java.util.logging.ConsoleHandler.formatter:设置的是处理器打印日志使用的格式化器
java.util.logging.SimpleFormatter.format:设置的是处理器打印日志的格式
1$:代表的是执行时间
2$:代表的是调用源(Jdk Log的调用源都为Jdk14LoggingImpl)
3$:代表的是日志名称
4$:代表的是日志级别
5$:代表的是日志内容
6$:代表的是异常信息
格式的定义需要参考java.util.Formatter类
三、引入日志配置
JDK Log提供了两种引入日志配置的方式:
1、class配置
我们在cn.horse.demo下新建JdkLogConfig类
JdkLogConfig类:
package cn.horse.demo;import java.io.IOException;
import java.io.InputStream;
import java.util.logging.LogManager;public class JdkLogConfig {public JdkLogConfig() {try {InputStream inputStream = ClassLoader.getSystemClassLoader().getResourceAsStream("logging.properties");LogManager.getLogManager().readConfiguration(inputStream);} catch (IOException e) {throw new RuntimeException(e);}}
}
在程序入口(main方法)配置系统属性:
System.setProperty("java.util.logging.config.class", "cn.horse.demo.JdkLogConfig");
或者在运行时配置VM参数:
-Djava.util.logging.config.class=cn.horse.demo.JdkLogConfig
class配置方式更加灵活,配置来源更加多样化,例如可以从Mysql、ldap等库中读取
2、file配置(不建议使用)
在程序入口(main方法)配置系统属性:
System.setProperty("java.util.logging.config.file", "日志配置文件的绝对路径(不能包含中文、空格)");
或者在运行时配置VM参数:
-Djava.util.logging.config.file=日志配置文件的绝对路径(不能包含中文、空格)
四、示例测试
测试:
这里我们查询年龄大于21岁的所有用户
StatementUtils.find("cn.horse.demo.UserInfoMapper.findByAge", 21);
执行的结果如下:
2023-08-29 16:11:27.767 详细 org.apache.ibatis.logging.LogFactory - Logging initialized using 'class org.apache.ibatis.logging.jdk14.Jdk14LoggingImpl' adapter.
2023-08-29 16:11:27.843 详细 org.apache.ibatis.logging.LogFactory - Logging initialized using 'class org.apache.ibatis.logging.jdk14.Jdk14LoggingImpl' adapter.
2023-08-29 16:11:27.854 详细 org.apache.ibatis.datasource.pooled.PooledDataSource - PooledDataSource forcefully closed/removed all connections.
2023-08-29 16:11:27.855 详细 org.apache.ibatis.datasource.pooled.PooledDataSource - PooledDataSource forcefully closed/removed all connections.
2023-08-29 16:11:27.855 详细 org.apache.ibatis.datasource.pooled.PooledDataSource - PooledDataSource forcefully closed/removed all connections.
2023-08-29 16:11:27.855 详细 org.apache.ibatis.datasource.pooled.PooledDataSource - PooledDataSource forcefully closed/removed all connections.
2023-08-29 16:11:27.900 详细 org.apache.ibatis.transaction.jdbc.JdbcTransaction - Opening JDBC Connection
2023-08-29 16:11:28.058 详细 org.apache.ibatis.datasource.pooled.PooledDataSource - Created connection 1379435698.
2023-08-29 16:11:28.059 详细 org.apache.ibatis.transaction.jdbc.JdbcTransaction - Setting autocommit to false on JDBC Connection [com.mysql.jdbc.JDBC4Connection@523884b2]
2023-08-29 16:11:28.062 详细 cn.horse.demo.UserInfoMapper.findByAge - ==> Preparing: SELECT ID, USERNAME name, AGE FROM T_USER WHERE AGE > ?
2023-08-29 16:11:28.094 详细 cn.horse.demo.UserInfoMapper.findByAge - ==> Parameters: 21(Integer)
2023-08-29 16:11:28.105 较详细 cn.horse.demo.UserInfoMapper.findByAge - <== Columns: ID, name, AGE
2023-08-29 16:11:28.106 较详细 cn.horse.demo.UserInfoMapper.findByAge - <== Row: 2, 李四, 22
2023-08-29 16:11:28.108 较详细 cn.horse.demo.UserInfoMapper.findByAge - <== Row: 3, 王五, 24
2023-08-29 16:11:28.109 详细 cn.horse.demo.UserInfoMapper.findByAge - <== Total: 2
2023-08-29 16:11:28.110 详细 org.apache.ibatis.transaction.jdbc.JdbcTransaction - Resetting autocommit to true on JDBC Connection [com.mysql.jdbc.JDBC4Connection@523884b2]
2023-08-29 16:11:28.113 详细 org.apache.ibatis.transaction.jdbc.JdbcTransaction - Closing JDBC Connection [com.mysql.jdbc.JDBC4Connection@523884b2]
2023-08-29 16:11:28.114 详细 org.apache.ibatis.datasource.pooled.PooledDataSource - Returned connection 1379435698 to pool.
{id: 2, name: 李四, age: 22}
{id: 3, name: 王五, age: 24}
在日志中我们可以看到预处理SQL语句,传递参数,返回的查询结果
2023-08-29 16:11:28.062 详细 cn.horse.demo.UserInfoMapper.findByAge - ==> Preparing: SELECT ID, USERNAME name, AGE FROM T_USER WHERE AGE > ?
2023-08-29 16:11:28.094 详细 cn.horse.demo.UserInfoMapper.findByAge - ==> Parameters: 21(Integer)
2023-08-29 16:11:28.105 较详细 cn.horse.demo.UserInfoMapper.findByAge - <== Columns: ID, name, AGE
2023-08-29 16:11:28.106 较详细 cn.horse.demo.UserInfoMapper.findByAge - <== Row: 2, 李四, 22
2023-08-29 16:11:28.108 较详细 cn.horse.demo.UserInfoMapper.findByAge - <== Row: 3, 王五, 24
2023-08-29 16:11:28.109 详细 cn.horse.demo.UserInfoMapper.findByAge - <== Total: 2
预处理:Preparing: SELECT ID, USERNAME name, AGE FROM T_USER WHERE AGE > ?
传递参数:Parameters: 21(Integer)
查询结果:Columns: ID, name, AGE
Row: 2, 李四, 22
Row: 3, 王五, 24
Total: 2
五、日志筛选
上面的日志信息打印了全部的详细日志记录,但是我只对SQL语句执行的过程比较关心,其他不相关的信息只会造成干扰,我们需要修改日志的配置,才能输出我们想要的日志信息:
handlers=java.util.logging.ConsoleHandler
.level=INFOcn.horse.demo.UserInfoMapper.level=FINER
java.util.logging.ConsoleHandler.level=ALL
java.util.logging.ConsoleHandler.formatter=java.util.logging.SimpleFormatter
java.util.logging.SimpleFormatter.format=%1$tY-%1$tm-%1$td %1$tT.%1$tL %4$s %3$s - %5$s%6$s%n
将全局的日志级别设置为INFO, 将日志名称以cn.horse.demo.UserInfoMapper命名空间开头的日志级别设置为FINER
测试:
这里我们查询年龄大于21岁的所有用户
StatementUtils.find("cn.horse.demo.UserInfoMapper.findByAge", 21);
执行的结果如下:
详细日志中只保留了SQL执行的过程