MyBatis日志输出和二级缓存

日志输出

日志输出格式

%c 输出日志信息所属的类的全名
%d 输出日志时间点的日期或时间，默认格式为ISO8601，也可以在其后指定格式，比如：%d{yyy-MM-dd HH:mm:ss }，输出类似：2019-10-26- 11：10：28
%f 输出日志信息所属的类的类名
%l 输出日志事件的发生位置，即输出日志信息的语句处于它所在的类的第几行
%m 输出代码中指定的信息，如log(message)中的message
%n 输出一个回车换行符，Windows平台为“rn”，Unix平台为“n”
%p 输出优先级，即DEBUG，INFO，WARN，ERROR，FATAL。如果是调用debug()输出的，则为DEBUG，依此类推
%r 输出自应用启动到输出该日志信息所耗费的毫秒数
%t 输出产生该日志事件的线程名

MyBatis找日志输出类实现日志输出的顺序

SL4j->Common logging->log4j2->log4j->jdkLoging->noLogging

MyBatis开启日志输出

导入log4j.jar

在mybatis-config.xml开启日志输出的配置

        <settings>
		<setting name="logImpl" value="LOG4J" /><!-- 开启日志 -->
	</settings>

编写log4j.properties放在类目录下

log4j.appender.stdout=org.apache.log4j.ConsoleAppender
log4j.appender.stdout.Target=System.out
log4j.appender.stdout.layout=org.apache.log4j.PatternLayout
log4j.appender.stdout.layout.ConversionPattern=%d{yyyy-MM-dd HH:mm:ss S} %5p %c:%l - %m%n
log4j.rootLogger=DEBUG, stdout

二级缓存

什么是二级缓存？

二级缓存是多个SqlSession共享的，其作用域是mapper的同一个namespace。

即，在不同的SqlSession中，相同的namespace下，相同的sql语句，并且sql模板中参数也相同的，会命中缓存。

第一次执行完毕会将数据库中查询的数据写到缓存，第二次会从缓存中获取数据将不再从数据库查询，从而提高查询效率。

MyBatis默认没有开启二级缓存，需要在全局配置(mybatis-config.xml)中开启二级缓存。

MyBatis配置二级缓存

在核心配置文件mybatis-config.xml中加入

<setting name="cacheEnabled" value="true"/>

在UsersMapper.xml中开启二缓存

<mapper namespace="com.yf.dao.UserDao">
	<cache></cache>

Pojo类必须实现序列化接口

测试

UserMapper.xml

<?xml version="1.0" encoding="UTF-8" ?>
<!DOCTYPE mapper
  PUBLIC "-//mybatis.org//DTD Mapper 3.0//EN"
  "http://mybatis.org/dtd/mybatis-3-mapper.dtd">
<mapper namespace="com.yf.dao.UserDao">
	<cache></cache>
	<select id="selectAllUsers" resultType="users">
		select * from t_user
	</select>
</mapper>

测试代码

SqlSession session=sqlSessionFactory.openSession();
UserDao userdao=session.getMapper(UserDao.class);
List<Users> users1=userdao.selectAllUsers();
System.out.println(users1);
session.close();
		
SqlSession session2=sqlSessionFactory.openSession();
UserDao userdao2=session2.getMapper(UserDao.class);
List<Users> users2=userdao2.selectAllUsers();
System.out.println(users2);
session.close();

日志输出显示

2019-10-26 10:39:41 420 DEBUG com.yf.dao.UserDao:org.apache.ibatis.cache.decorators.LoggingCache.getObject(LoggingCache.java:62) - Cache Hit Ratio [com.yf.dao.UserDao]: 0.0
2019-10-26 10:39:41 429 DEBUG org.springframework.jdbc.datasource.DataSourceUtils:org.springframework.jdbc.datasource.DataSourceUtils.doGetConnection(DataSourceUtils.java:110) - Fetching JDBC Connection from DataSource
2019-10-26 10:39:41 750 DEBUG org.mybatis.spring.transaction.SpringManagedTransaction:org.mybatis.spring.transaction.SpringManagedTransaction.openConnection(SpringManagedTransaction.java:86) - JDBC Connection [jdbc:oracle:thin:@localhost:1521:orcl, UserName=SCOTT, Oracle JDBC driver] will not be managed by Spring
2019-10-26 10:39:41 756 DEBUG com.yf.dao.UserDao.selectAllUsers:org.apache.ibatis.logging.jdbc.BaseJdbcLogger.debug(BaseJdbcLogger.java:139) - ==>  Preparing: select * from t_user 
2019-10-26 10:39:41 833 DEBUG com.yf.dao.UserDao.selectAllUsers:org.apache.ibatis.logging.jdbc.BaseJdbcLogger.debug(BaseJdbcLogger.java:139) - ==> Parameters: 
2019-10-26 10:39:41 869 DEBUG com.yf.dao.UserDao.selectAllUsers:org.apache.ibatis.logging.jdbc.BaseJdbcLogger.debug(BaseJdbcLogger.java:139) - <==      Total: 4
[Users [id=2, username=lisi, address=深圳, birthday=Thu Oct 24 14:26:40 CST 2019], Users [id=3, username=lisi2, address=广州, birthday=Thu Oct 24 14:26:41 CST 2019], Users [id=4, username=lisi3, address=南宁, birthday=Thu Oct 24 14:26:42 CST 2019], Users [id=5, username=lisi4, address=北海, birthday=Thu Oct 24 14:26:43 CST 2019]]
2019-10-26 10:39:41 879 DEBUG org.springframework.jdbc.datasource.DataSourceUtils:org.springframework.jdbc.datasource.DataSourceUtils.doReleaseConnection(DataSourceUtils.java:327) - Returning JDBC Connection to DataSource
2019-10-26 10:39:41 928 DEBUG com.yf.dao.UserDao:org.apache.ibatis.cache.decorators.LoggingCache.getObject(LoggingCache.java:62) - Cache Hit Ratio [com.yf.dao.UserDao]: 0.5
[Users [id=2, username=lisi, address=深圳, birthday=Thu Oct 24 14:26:40 CST 2019], Users [id=3, username=lisi2, address=广州, birthday=Thu Oct 24 14:26:41 CST 2019], Users [id=4, username=lisi3, address=南宁, birthday=Thu Oct 24 14:26:42 CST 2019], Users [id=5, username=lisi4, address=北海, birthday=Thu Oct 24 14:26:43 CST 2019]]

由日志可知SQL语句只查询一次，第二次查询直接访问mapper缓存。