日志（logback）关于特定方法时延的跟踪

朱雀 2023-07-15 18:28 108阅读 0赞

### 背景 ###

目前项目在BI 这块很慢，为了定位问题，需要去跟踪在哪一块导致了这方面的问题，比如获取连接，还是数据处理，展示等等，为了有个直观性、持续性的标识，这边定的使用日志去来定位问题。

# 解决 #

1、我们先写了个静态方法，静态方法如下图所示

/**
         * @param type "开始"和"结束"两个值
         * @param function 功能项，比如"初始化"，"偏移列"
         * @param info 具体的信息，比如调用的方法"opertator.execute()"
         * @param currentTime 调用方法时的当前系统时间System.currentTimeMillis();
         * @param totalTime type参数为"结束"时，该值要传过来，表示执行"开始"至"结束"这段代码的持续毫秒数
         */
        public static void log(String type, String function, String info, long currentTime, long totalTime){
            SimpleDateFormat dateformat = new SimpleDateFormat("yyyy-MM-dd HH:mm:ss.SSS");
            logger.info("type:{},function:{},info:{},系统开始时间:{},耗时ms{}",type,function,info,dateformat.format(currentTime),totalTime);
        }

*  2、这样在各自开发的模块关键的起始和终止方法上调用相应的方法，记录一个方法所需要的的时间，这样耗时便一目了然了。  
    调用如下所示

long start = System.currentTimeMillis();
    LogUtil.log("开始", "", "HiveJdbcExecutor.createTableWithJosnAndDataByExplore", start, 0);
    
    long end = System.currentTimeMillis();
    LogUtil.log("结束", "", "HiveJdbcExecutor.createTableWithJosnAndDataByExplore", end, end - start);

*  3、在logback.xml 上进行配置（仅展示相关代码）

<logger name="com.bonc.ds.utils.LogUtil" level="INFO" additivity="false">
                <appender-ref ref="LOGFOREXPLORE"/>
        </logger>
       <appender name="LOGFOREXPLORE"  class="ch.qos.logback.core.rolling.RollingFileAppender">
            <rollingPolicy class="ch.qos.logback.core.rolling.TimeBasedRollingPolicy">
                
                <FileNamePattern>${LOG_HOME}/datascience.explore.log.%d{yyyy-MM-dd}.log</FileNamePattern>
                
                <MaxHistory>30</MaxHistory>
            </rollingPolicy>
            <encoder class="ch.qos.logback.classic.encoder.PatternLayoutEncoder">
                
                <pattern>%d{yyyy-MM-dd HH:mm:ss.SSS} [%thread] %-5level %logger{50} - %msg%n</pattern>
            </encoder>
            
            <triggeringPolicy class="ch.qos.logback.core.rolling.SizeBasedTriggeringPolicy">
                <MaxFileSize>10MB</MaxFileSize>
            </triggeringPolicy>
        </appender>

### 总结 ###

经过这几个步骤，便可以完成这样的效果。去定位方法时延的问题