spring StopWatch监控请求执行时间

落日映苍穹つ 2022-03-09 07:18 317阅读 0赞

为什么谈到StopWatch这个对象类，主要是在spring boot的启动流程中发现了它的应用。  
所以这里简单分析下它的作用和使用场景。

spring boot启动中的使用，统计整个启动流程的执行时间：

public ConfigurableApplicationContext run(String... args) {
            StopWatch stopWatch = new StopWatch();
            stopWatch.start();
            ConfigurableApplicationContext context = null;
            Collection<SpringBootExceptionReporter> exceptionReporters = new ArrayList();
            this.configureHeadlessProperty();
            SpringApplicationRunListeners listeners = this.getRunListeners(args);
            listeners.starting();
           ……
         stopWatch.stop();
                if (this.logStartupInfo) {
                    (new StartupInfoLogger(this.mainApplicationClass)).logStarted(this.getApplicationLog(), stopWatch);
                }

**背景**  
有时我们在做开发的时候需要记录每个任务执行时间，或者记录一段代码执行时间，最简单的方法就是打印当前时间与执行完时间的差值，然后这样如果执行大量测试的话就很麻烦，并且不直观，如果想对执行的时间做进一步控制，则需要在程序中很多地方修改，目前spring-framework提供了一个StopWatch类可以做类似任务执行时间控制，也就是封装了一个对开始时间，结束时间记录操作的Java类，小例一则如下

**实例**

package com.example.stopwatch;
    
    import org.springframework.util.StopWatch;
    
    
    public class TestStopWatch {
        private void test() throws InterruptedException {
            StopWatch sw = new StopWatch();
    
            sw.start("起床");
            Thread.sleep(1000);
            sw.stop();
    
            sw.start("洗漱");
            Thread.sleep(2000);
            sw.stop();
    
            sw.start("锁门");
            Thread.sleep(500);
            sw.stop();
    
            System.out.println(sw.prettyPrint());
            System.out.println(sw.getTotalTimeMillis());
            System.out.println(sw.getLastTaskName());
            System.out.println(sw.getLastTaskInfo());
            System.out.println(sw.getTaskCount());
        }
    
    
        public static void main(String []argv) throws InterruptedException {
            TestStopWatch testStopWatch = new TestStopWatch();
            testStopWatch.test();
        }
    }

**结果**

StopWatch '': running time (millis) = 3518
    -----------------------------------------
    ms     %     Task name
    -----------------------------------------
    00998  028%  起床
    02020  057%  洗漱
    00500  014%  锁门
     
    3518
    锁门
    org.springframework.util.StopWatch$TaskInfo@5b2133b1
    3

一个StopWatch可以监控多个任务并分别进行统计。

在需要对一些方法和请求进行时间统计的时候，还是非常好用的。比如日志切面中对执行方法时长的分析。  
下面来一个小例子：  
在filter 的doFilter中加入如下代码

StopWatch stopWatch = new StopWatch(url+System.currentTimeMillis());  
    stopWatch.start();  
     doFilter(arg0,arg1);  
     opWatch.stop();  
      
    loginfo(stopWatch.getTotalTimeMillis()+"---"+request.getRequestURI()+"执行时间")；