java

关注公众号 jb51net

关闭
首页 > 软件编程 > java > Mybatis拦截慢SQL日志记录

Mybatis之如何拦截慢SQL日志记录

作者:不爱编程的设计师

这篇文章主要介绍了Mybatis之如何拦截慢SQL日志记录问题,具有很好的参考价值,希望对大家有所帮助。如有错误或未考虑完全的地方,望不吝赐教

Mybatis拦截慢SQL日志记录

通过分析MySQL慢查询日志不方便,利用数据源不直观,无法了解上下文调用关系

要求通过可配置化的操作实现日志在控制台的输出和在数据库中记录

本文利用SpringBoot和Mybatis,基于Mybatis Interceptor实现慢SQL拦截日志记录和打印输出

服务配置

# slow-sql
# 基于interceptor
slow-sql-interceptor.enabled=true
# 设置超时时间ms
slow-sql.timeout=10
# 日志是否插入数据库
slow-sql.insertDB=true

日志实体类

@Data
public class SqlLog {
    // 主键
    private Long id;
    // 调用方法名称
    private String methodName;
    // 参数列表
    private String params;
    // 方法执行时间
    private Long runTime;
    // 执行SQL
    private String executeSql;
    // SQL类型
    private String sqlType;
    // 记录创建时间
    private Date createTime;
}

SQL拦截器

@Intercepts({@Signature(type = StatementHandler.class, method = "query", args = {Statement.class, ResultHandler.class}),
             @Signature(type = StatementHandler.class, method = "update", args = {Statement.class}),
             @Signature(type = StatementHandler.class, method = "batch", args = {Statement.class})})
@Component
@Slf4j
// 配置文件读取是否启用此配置
@ConditionalOnProperty(prefix = "slow-sql-interceptor", name = "enabled", havingValue = "true")
public class SqlExecuteTimeInterceptor implements Interceptor {
    // 日志记录mapper
    @Autowired
    private LogMapper logMapper;
    // 是否插入数据库标识
    @Value("${slow-sql.insertDB}")
    private boolean isInsertDB;
    // 超时时间
    @Value("${slow-sql.timeout}")
    private Long timeout;
    /**
     * @Author thailandking
     * @Date 2020/2/16 16:44
     * @LastEditors thailandking
     * @LastEditTime 2020/2/16 16:44
     * @Description sql拦截器
     */
    @Override
    public Object intercept(Invocation invocation) throws Throwable {
        Object target = invocation.getTarget();
        long begin = System.currentTimeMillis();
        StatementHandler statementHandler = (StatementHandler) target;
        try {
            return invocation.proceed();
        } finally {
            long end = System.currentTimeMillis();
            // 判断超时
            if ((end - begin) > timeout) {
                // 方法名称、类型
                MetaObject metaObject = MetaObject.forObject(statementHandler, SystemMetaObject.DEFAULT_OBJECT_FACTORY, SystemMetaObject.DEFAULT_OBJECT_WRAPPER_FACTORY, new DefaultReflectorFactory());
                MappedStatement mappedStatement = (MappedStatement) metaObject.getValue("delegate.mappedStatement");
                String methodName = mappedStatement.getId();
                String sqlType = mappedStatement.getSqlCommandType().toString();
                // 控制台打印日志
                BoundSql boundSql = statementHandler.getBoundSql();
                String sql = boundSql.getSql();
                // 参数map
                Object parameterObject = boundSql.getParameterObject();
                // 参数列表
                List<ParameterMapping> parameterMappingList = boundSql.getParameterMappings();
                // 格式化sql语句,去除换行符,替换参数
                sql = formatSQL(sql, parameterObject, parameterMappingList);
                log.error("执行 SQL:[ {} ], 执行耗时[ {} ms ]", sql, (end - begin));
                // 判断是否写入数据库
                if (isInsertDB) {
                    // 转换参数map为String
                    String params = changeSqlMapParams(parameterObject, parameterMappingList);
                    SqlLog log = new SqlLog();
                    log.setMethodName(methodName);
                    log.setParams(params);
                    log.setRunTime((end - begin));
                    log.setExecuteSql(sql);
                    log.setSqlType(sqlType);
                    logMapper.insertSqlLog(log);
                }
            }
        }
    }
    private String changeSqlMapParams(Object parameterObject, List<ParameterMapping> parameterMappingList) {
        StringBuilder sb = new StringBuilder();
        Map<String, Object> params = (Map<String, Object>) parameterObject;
        for (ParameterMapping pm : parameterMappingList) {
            if (pm.getMode().name().equals("IN")) {
                String param = ",[" + params.get(pm.getProperty()).toString() + "]";
                sb.append(param);
            }
        }
        return sb.length() > 0 ? sb.substring(1) : null;
    }
    private String formatSQL(String sql, Object parameterObject, List<ParameterMapping> parameterMappingList) {
        if (sql == null || sql.length() == 0) {
            return "";
        }
        // 去除换行符
        sql = sql.replaceAll("[\\s\n ]+", "  ");
        // 替换参数
        Map<String, Object> params = (Map<String, Object>) parameterObject;
        for (ParameterMapping pm : parameterMappingList) {
            if (pm.getMode().name().equals("IN")) {
                sql = sql.replaceFirst("\\?", params.get(pm.getProperty()).toString());
            }
        }
        return sql;
    }
    @Override
    public Object plugin(Object target) {
        return Plugin.wrap(target, this);
    }
    @Override
    public void setProperties(Properties properties) {
    }
}

效果展示

Mybatis拦截器打印完整sql日志

开始创建拦截器,创建自定义拦截器必须实现Interceptor接口,那么我们新建一个SqlLoggerInterceptor类并实现Interceptor接口,并且通过@Intercepts和@Signature注解来配置拦截的方法和对象

我们经常用到的就只有update和两个query,因此在@Intercepts内写上3个Signature,如:

可以看出update、query、query中的args中的对象是和Executor中的update、query、query方法中的参数是一一对应的,实现Interceptor接口中的方法,主要有三个方法分别是plugin、setProperties、intercept,而我们主要做的就是intercept方法,plugin方法一般固定即可,setProperties是在外部传近来的值

创建拦截器,实现

org.apache.ibatis.plugin.Interceptor

@Slf4j
@Intercepts({
    //type指定代理的是那个对象,method指定代理Executor中的那个方法,args指定Executor中的query方法都有哪些参数对象
    //由于Executor中有两个query,因此需要两个@Signature
    @Signature(type = Executor.class, method = "update", args = {MappedStatement.class, Object.class}),//需要代理的对象和方法
    @Signature(type = Executor.class, method = "query", args = {MappedStatement.class, Object.class, RowBounds.class,
        ResultHandler.class, CacheKey.class, BoundSql.class}),
    @Signature(type = Executor.class, method = "query", args = {MappedStatement.class, Object.class, RowBounds.class,
        ResultHandler.class})//需要代理的对象和方法
})
public class SqlLoggerInterceptor implements Interceptor {
  @Override
  public Object intercept(Invocation invocation) throws Throwable {
    MappedStatement mappedStatement = (MappedStatement) invocation.getArgs()[0];
    Object parameter = null;
    if (invocation.getArgs().length > 1) {
      //获得查询方法的参数,比如selectById(Integer id,String name),那么就可以获取到四个参数分别是:
      //{id:1,name:"user1",param1:1,param2:"user1"}
      parameter = invocation.getArgs()[1];
    }
    String sqlId = mappedStatement.getId();//获得mybatis的*mapper.xml文件中映射的方法,如:com.best.dao.UserMapper.selectById
    //将参数和映射文件组合在一起得到BoundSql对象
    BoundSql boundSql = mappedStatement.getBoundSql(parameter);
    //获取配置信息
    Configuration configuration = mappedStatement.getConfiguration();
    Object returnValue = null;
    log.debug("/*---------------java:" + sqlId + "[begin]---------------*/");
    //通过配置信息和BoundSql对象来生成带值得sql语句
    String sql = geneSql(configuration, boundSql);
    //打印sql语句
    log.info("==> mysql : " + sql);
    //先记录执行sql语句前的时间
    long start = System.currentTimeMillis();
    try {
      //开始执行sql语句
      returnValue = invocation.proceed();
    } catch (InvocationTargetException | IllegalAccessException e) {
      e.printStackTrace();
    }
    //记录执行sql语句后的时间
    long end = System.currentTimeMillis();
    //得到执行sql语句的用了多长时间
    long time = (end - start);
    //以毫秒为单位打印
    log.debug("<== sql执行历时:" + time + "毫秒");
    //返回值,如果是多条记录,那么此对象是一个list,如果是一个bean对象,那么此处就是一个对象,也有可能是一个map
    return returnValue;
  }
  /**
   * 如果是字符串对象则加上单引号返回,如果是日期则也需要转换成字符串形式,如果是其他则直接转换成字符串返回。
   *
   * @param obj
   * @return
   */
  private static String getParameterValue(Object obj) {
    String value;
    if (obj instanceof String) {
      value = "'" + obj.toString() + "'";
    } else if (obj instanceof Date) {
      DateFormat formatter = DateFormat.getDateTimeInstance(DateFormat.DEFAULT, DateFormat.DEFAULT, Locale.CHINA);
      value = "'" + formatter.format(obj) + "'";
    } else {
      if (obj != null) {
        value = obj.toString();
      } else {
        value = "";
      }
    }
    return value;
  }
  /**
   * 生成对应的带有值得sql语句
   *
   * @param configuration
   * @param boundSql
   * @return
   */
  public static String geneSql(Configuration configuration, BoundSql boundSql) {
    Object parameterObject = boundSql.getParameterObject();//获得参数对象,如{id:1,name:"user1",param1:1,param2:"user1"}
    List<ParameterMapping> parameterMappings = boundSql.getParameterMappings();//获得映射的对象参数
    String sql = boundSql.getSql().replaceAll("[\\s]+", " ");//获得带问号的sql语句
    if (parameterMappings.size() > 0 && parameterObject != null) {//如果参数个数大于0且参数对象不为空,说明该sql语句是带有条件的
      TypeHandlerRegistry typeHandlerRegistry = configuration.getTypeHandlerRegistry();
      if (typeHandlerRegistry.hasTypeHandler(parameterObject.getClass())) {//检查该参数是否是一个参数
        //getParameterValue用于返回是否带有单引号的字符串,如果是字符串则加上单引号
        sql = sql.replaceFirst("\\?", getParameterValue(parameterObject));//如果是一个参数则只替换一次,将问号直接替换成值
      } else {
        MetaObject metaObject = configuration.newMetaObject(parameterObject);//将映射文件的参数和对应的值返回,比如:id,name以及对应的值。
        for (ParameterMapping parameterMapping : parameterMappings) {//遍历参数,如:id,name等
          String propertyName = parameterMapping.getProperty();//获得属性名,如id,name等字符串
          if (metaObject.hasGetter(propertyName)) {//检查该属性是否在metaObject中
            Object obj = metaObject.getValue(propertyName);//如果在metaObject中,那么直接获取对应的值
            sql = sql.replaceFirst("\\?", getParameterValue(obj));//然后将问号?替换成对应的值。
          } else if (boundSql.hasAdditionalParameter(propertyName)) {
            Object obj = boundSql.getAdditionalParameter(propertyName);
            sql = sql.replaceFirst("\\?", getParameterValue(obj));
          }
        }
      }
    }
    return sql;//最后将sql语句返回
  }
  @Override
  public Object plugin(Object target) {
    return Plugin.wrap(target, this);
  }
  @Override
  public void setProperties(Properties properties) {
    //properties可以通过
    System.out.println(properties);
  }
}

此拦截写好之后还需要springboot使用,如果是通过@Component扫描是无法正常注入到IOC容器中的,因此需要通过@Configuration和@Bean的方式进行,新建一个配置BatisPlusConfig类,代码如下

@Configuration
public class BatisPlusConfig {
  @Bean
  public String myInterceptor(SqlSessionFactory sqlSessionFactory) {
    //实例化插件
    SqlLoggerInterceptor sqlInterceptor = new SqlLoggerInterceptor();
    //创建属性值
    Properties properties = new Properties();
    properties.setProperty("prop1", "value1");
    //将属性值设置到插件中
    sqlInterceptor.setProperties(properties);
    //将插件添加到SqlSessionFactory工厂
    sqlSessionFactory.getConfiguration().addInterceptor(sqlInterceptor);
    return "interceptor";
  }
}

总结

OK啦~以上为个人经验,希望能给大家一个参考,也希望大家多多支持脚本之家。 

您可能感兴趣的文章:
阅读全文