使用 StopWatch 优雅打印执行耗时

Posted Java知音_

tags:

篇首语:本文由小常识网(cha138.com)小编为大家整理,主要介绍了使用 StopWatch 优雅打印执行耗时相关的知识,希望对你有一定的参考价值。

点击关注公众号,实用技术文章及时了解

来源:ningyu1.github.io/20190505/

116-stop-watch.html

0x01:背景

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

示例

先来看几个示例

  • 统计输出总耗时

import org.springframework.util.StopWatch;  
  
public class SpringStopWatchExample   
  
    public static void main (String[] args) throws InterruptedException   
        StopWatch sw = new StopWatch();  
        sw.start();  
        //long task simulation  
        Thread.sleep(1000);  
        sw.stop();  
        System.out.println(sw.getTotalTimeMillis());  
      

输出

1013
  • 输出最后一个任务的耗时

public class SpringStopWatchExample2   
  
    public static void main (String[] args) throws InterruptedException   
        StopWatch sw = new StopWatch();  
        sw.start("A");//setting a task name  
        //long task simulation  
        Thread.sleep(1000);  
        sw.stop();  
        System.out.println(sw.getLastTaskTimeMillis());  
      

输出

1009
  • 以优雅的格式打出所有任务的耗时以及占比

import org.springframework.util.StopWatch;  
  
public class SpringStopWatchExample3   
  
    public static void main (String[] args) throws InterruptedException   
        StopWatch sw = new StopWatch();  
        sw.start("A");  
        Thread.sleep(500);  
        sw.stop();  
        sw.start("B");  
        Thread.sleep(300);  
        sw.stop();  
        sw.start("C");  
        Thread.sleep(200);  
        sw.stop();  
        System.out.println(sw.prettyPrint());  
      

输出

StopWatch '': running time (millis) = 1031  
-----------------------------------------  
ms     %     Task name  
-----------------------------------------  
00514  050%  A  
00302  029%  B  
00215  021%  C:
  • 序列服务输出耗时信息

@Override  
public long nextSeq(String name)   
    StopWatch watch = new StopWatch();  
    watch.start("单序列获取总消耗");  
    long sequence = generator.generateId(name);  
    watch.stop();  
    logger.info(watch.prettyPrint());  
    return sequence;  

0x02:更多用法

不同的打印结果

  • getTotalTimeSeconds() 获取总耗时秒,同时也有获取毫秒的方法

  • prettyPrint() 优雅的格式打印结果,表格形式

  • shortSummary() 返回简短的总耗时描述

  • getTaskCount() 返回统计时间任务的数量

  • getLastTaskInfo().getTaskName() 返回最后一个任务TaskInfo对象的名称

更多查看文档

https://docs.spring.io/spring-framework/docs/current/javadoc-api/org/springframework/util/StopWatch.html

0x03:总结

以后统计代码执行效率建议大家都使用这个工具来进行输出,不需要在starttime、endtime再相减计算,用优雅的方式来完成这件事情。

推荐:

主流Java进阶技术(学习资料分享)

PS:因为公众号平台更改了推送规则,如果不想错过内容,记得读完点一下“在看”,加个“星标”,这样每次新文章推送才会第一时间出现在你的订阅列表里。点“在看”支持我们吧!

以上是关于使用 StopWatch 优雅打印执行耗时的主要内容,如果未能解决你的问题,请参考以下文章

别再用System.currentTimeMillis()!拥抱StopWatch优雅计算程序执行耗时

这 4 种,统计代码执行耗时,才足够优雅!

别再用 System.currentTimeMillis 统计耗时了,太 Low,试试 Spring Boot 源码在用的 StopWatch吧,够优雅

别再用 System.currentTimeMillis 统计耗时了,太 Low,试试 Spring Boot 源码在用的 StopWatch吧,够优雅

StopWatch的使用

我的Android进阶之旅NDK开发之在C++代码中使用Android Log打印日志,打印出C++的函数耗时以及代码片段耗时详情