java通过拦截器实现项目每次执行sql耗时统计,可配置是否打印

本文涉及的产品
日志服务 SLS,月写入数据量 50GB 1个月
简介: java通过拦截器实现项目每次执行sql耗时统计,可配置是否打印
+关注继续查看

前言 

 我们平常在跑项目的时候,有时候一不留神,写了一个慢sql,导致整个系统变的很慢,但是我们有不知道是哪个sql导致的,这段代码,就能够实现我们想要的功能


既可以统计sql耗时,又可以定位到执行sql的是哪段代码,还可以拿到完整替换过?号的完整sql,直接复制下来,就能够在sql执行器里面执行,然后你就可以通过explain去分析为什么慢了,是不是因为索引导致的等等


还有你们平常用到的idea的mybatis log插件,将?号替换成完整sql也是通过这个逻辑完成的


实现的效果

  • 1.时间超过n秒的要打印,n秒通过配置形式
  • 2.可配置打印级别info/debug
  • 3.Sql打印日志开关,如果为关就不打印sql日志
  • 4.日志格式:耗时、sql执行的所在dao层和方法、sql完整语句


默认设置

  • 执行时间>=3s,要打印sql
  • Info级别
  • 默认开关为开,打印sql
  • 日志格式:eg:执行sql耗时:5023 ms - id:com.xxxx.xxxx.getXxxxx - Sql:select id,name,age from student


完整代码 

 
package cn.zygxsq.example.common.mybatis;
 
import lombok.extern.slf4j.Slf4j;
import org.apache.ibatis.cache.CacheKey;
import org.apache.ibatis.executor.Executor;
import org.apache.ibatis.mapping.BoundSql;
import org.apache.ibatis.mapping.MappedStatement;
import org.apache.ibatis.mapping.ParameterMapping;
import org.apache.ibatis.mapping.ParameterMode;
import org.apache.ibatis.plugin.*;
import org.apache.ibatis.reflection.MetaObject;
import org.apache.ibatis.session.Configuration;
import org.apache.ibatis.session.ResultHandler;
import org.apache.ibatis.session.RowBounds;
import org.apache.ibatis.type.TypeHandlerRegistry;
import org.springframework.beans.factory.annotation.Value;
import org.springframework.stereotype.Component;
 
import java.text.DateFormat;
import java.text.SimpleDateFormat;
import java.util.Date;
import java.util.List;
import java.util.regex.Matcher;
 
 
@Slf4j
@Component
@Intercepts({
        @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}),
        @Signature(type = Executor.class, method = "queryCursor", args = {MappedStatement.class, Object.class, RowBounds.class})
})
public class ExecutorSqlInterceptor implements Interceptor {
 
     /**
     * 超过该时间打印sql
     */
    @Value("${mybatis.sql.log.time}")
    private BigDecimal logTime;
 
    /**
     * 日志级别
     */
    @Value("${mybatis.sql.log.logLevel}")
    private String logLevel;
 
    /**
     * 日志开关
     */
    @Value("${mybatis.sql.log.switch}")
    private String logSwitch;
 
    /**
     * DATE_FORMAT
     */
    private static final DateFormat DATE_FORMAT = new SimpleDateFormat("yyyy-MM-dd HH:mm:ss");
 
    /**
     * 关
     */
    private static final String SWITCH_FALSE = "false";
 
 
    @Override
    public Object intercept(Invocation invocation) throws Throwable {
        try {
            if (SWITCH_FALSE.equalsIgnoreCase(logSwitch)){
                return invocation.proceed();
            }
 
            long start = System.currentTimeMillis();
 
            Object result = invocation.proceed();
 
            long end = System.currentTimeMillis();
            long timing = end - start;
            // 打印3s以上的sql语句
            BigDecimal timingBigDecimal = new BigDecimal(timing);
            BigDecimal maxTime = logTime.multiply(new BigDecimal("1000"));
            if (timingBigDecimal.compareTo(maxTime)>=0) {
                MappedStatement mappedStatement = (MappedStatement) invocation.getArgs()[0];
                Object parameterObject = null;
                if (invocation.getArgs().length > 1) {
                    parameterObject = invocation.getArgs()[1];
                }
                String statementId = mappedStatement.getId();
                BoundSql boundSql = mappedStatement.getBoundSql(parameterObject);
                Configuration configuration = mappedStatement.getConfiguration();
                String sql = getSql(boundSql, parameterObject, configuration);
                switch (logLevel){
                    case "debug":
                        if (log.isDebugEnabled()){
                            log.debug("执行sql耗时:{} ms - id:{} - Sql:{}", timing, statementId, sql);
                        }
                        break;
                    default:
                        if (log.isInfoEnabled()){
                            log.info("执行sql耗时:{} ms - id:{} - Sql:{}", timing, statementId, sql);
                        }
                }
            }
            return result;
        }catch (Exception e){
            log.error("拦截sql异常:",e);
        }
        return invocation.proceed();
 
    }
 
 
 
    @Override
    public Object plugin(Object target) {
        return Plugin.wrap(target, this);
    }
 
    private String getSql(BoundSql boundSql, Object parameterObject, Configuration configuration) {
        String sql = boundSql.getSql().replaceAll("[\\s]+", " ");
        List<ParameterMapping> parameterMappings = boundSql.getParameterMappings();
        TypeHandlerRegistry typeHandlerRegistry = configuration.getTypeHandlerRegistry();
        if (parameterMappings != null) {
            for (int i = 0; i < parameterMappings.size(); i++) {
                ParameterMapping parameterMapping = parameterMappings.get(i);
                if (parameterMapping.getMode() != ParameterMode.OUT) {
                    Object value;
                    String propertyName = parameterMapping.getProperty();
                    if (boundSql.hasAdditionalParameter(propertyName)) {
                        value = boundSql.getAdditionalParameter(propertyName);
                    } else if (parameterObject == null) {
                        value = null;
                    } else if (typeHandlerRegistry.hasTypeHandler(parameterObject.getClass())) {
                        value = parameterObject;
                    } else {
                        MetaObject metaObject = configuration.newMetaObject(parameterObject);
                        value = metaObject.getValue(propertyName);
                    }
                    sql = replacePlaceholder(sql, value);
                }
            }
        }
        return sql;
    }
 
    private String replacePlaceholder(String sql, Object propertyValue) {
        String result;
        if (propertyValue != null) {
            if (propertyValue instanceof String) {
                result = "'" + propertyValue + "'";
            } else if (propertyValue instanceof Date) {
                result = "'" + DATE_FORMAT.format(propertyValue) + "'";
            } else {
                result = propertyValue.toString();
            }
        } else {
            result = "null";
        }
        return sql.replaceFirst("\\?", Matcher.quoteReplacement(result));
    }
 
}

 配置文件

######慢sql日志打印
#超过该事件打印,单位s
mybatis.sql.log.time=3
#打印级别,info/debug
mybatis.sql.log.logLevel=info
#是否打印日志开关,true开,false关
mybatis.sql.log.switch=true

代码简要分析 

@Intercepts({
        @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}),
        @Signature(type = Executor.class, method = "queryCursor", args = {MappedStatement.class, Object.class, RowBounds.class})
})

 @Intercepts注解只有一个属性,即value,其返回值类型是一个@Signature类型的数组,表示我们可以配置多个@Signature注解。


@Signature注解其实就是一个方法签名,其共有三个属性,分别为:


type指接口的class,


method指接口中的方法名,


args指的是方法参数类型(该属性返回值是一个数组)。


顾名思义,上述就是,我要拦截Executor这个类的update、query、queryCursor这个4个方法


大家可以看下Executor这个类里面是不是有这4个方法

image

 update和query大家应该都知道是增删改查,queryCursor主要是执行存储过程用的。

里面的具体方法是做什么的,可以参考一下这篇博客:

Mybatis源码解读系列(五)-Executor__微风轻起的博客-CSDN博客

相关实践学习
日志服务之使用Nginx模式采集日志
本文介绍如何通过日志服务控制台创建Nginx模式的Logtail配置快速采集Nginx日志并进行多维度分析。
相关文章
|
3天前
|
Java 测试技术
828. 统计子串中的唯一字符 --力扣 --JAVA
我们定义了一个函数 countUniqueChars(s) 来统计字符串 s 中的唯一字符,并返回唯一字符的个数。
21 2
|
3天前
|
Java
2824. 统计和小于目标的下标对数目 --力扣 --JAVA
给你一个下标从 0 开始长度为 n 的整数数组 nums 和一个整数 target ,请你返回满足 0 <= i < j < n 且 nums[i] + nums[j] < target 的下标对 (i, j) 的数目。
14 0
|
18天前
|
存储 Java
统计指定月份的工作日数量的Java程序实现
统计指定月份的工作日数量的Java程序实现
|
26天前
|
Java Apache Spring
Java统计耗时
Java统计耗时
|
3月前
|
消息中间件 Dubbo Java
大厂必刷!国内顶级“Java面试总汇2023”跳槽底气 统计通过率95%
前言 今天已经是金九银十的第15天了。不知现在有多少小伙伴找到自己满意的工作了。今年的面试比以往几年的都要多些难度。随着学习it的人越来越多竞争也是越来越多,越来越卷
29 0
|
4月前
|
Java C语言
字符串统计-C语言/Java
字符串统计-C语言/Java
50 0
|
4月前
|
消息中间件 Dubbo Java
大厂必刷!国内顶级“Java面试总汇2023”跳槽底气 统计通过率95%
金九银十即将开始。不知现在有多少小伙伴找到自己满意的工作了。今年的面试比以往几年的都要多些难度。随着学习it的人越来越多竞争也是越来越多,越来越卷。
|
4月前
|
存储 Java 应用服务中间件
Java统计用户在线人数,这样做才优雅
统计用户在线人数 在统计用户在人数的时候,我们用到了监听器,监听器大致分为以下三种: 1. ServletRequestListener:用于监听请求的监听接口 2. HttpSessionListener:用于监听会话的监听接口 3. ServletContextListener:用于监听应用的回话接口
99 1
|
5月前
|
Java 应用服务中间件
Java Web应用开发案例|使用监听器统计Web站点的在线用户数
HttpSessionListener监听器,主要用于监听HttpSession对象的生命期变化,如创建session和释放session事件。一个session代表一个在线用户,因此常用HttpSessionListener监听器跟踪管理在线用户。
94 0
Java Web应用开发案例|使用监听器统计Web站点的在线用户数
|
7月前
|
存储 分布式计算 Java
JAVA Spark rdd使用Spark编程实现:统计出每个省份广 告被点击次数的TOP3
JAVA Spark rdd使用Spark编程实现:统计出每个省份广 告被点击次数的TOP3
推荐文章
更多