使用 StopWatch 优雅计算代码耗时

使用 StopWatch 优雅计算代码耗时

原始情况下, 我们通过 System.currentTimeMillis() 相减计算某段代码的耗时
其实, Spring 已经内置了使用 StopWatch 优雅计算代码耗时
其中包含了耗时的时间和占比

 /**
     * stopWatch
     * 用来统计代码耗时和占比, 分析代码执行耗时分布很好用
     * 每次统计前, start('可读标识'), 每次统计后 stop(), 最后 prettyPrint
     *
     * 注意: 一次 start 下面就要对应 stop 而不能是 start, 会异常 Can't start StopWatch: it's already running
     */
    @Test
    public void testStopWatch() throws Exception {
        StopWatch sw = new StopWatch();

        sw.start("起床");
        TimeUnit.SECONDS.sleep(1);
        sw.stop();

        // start 之前必须 stop 之前的任务, 否则报错
        sw.start("洗漱");
        TimeUnit.SECONDS.sleep(2);
        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());

        // 简短汇总
        System.out.println(sw.shortSummary());
    }

如图, 可以直观的看到任务的耗时和占比, 以本例说明, 洗漱的耗时时间占用较久, 需要优化

注意, StopWatch 在使用时, 一次 start 对应 一次 stop, 中间不能插入新的 start, 需要 stop 之后, 再开启新的 start