4

使用StopWatch优雅的输出执行耗时

 9 months ago
source link: https://ningyu1.github.io/20190505/116-stop-watch.html
Go to the source link to view the article. You can view the picture content, updated content and better typesetting reading experience. If the link is broken, please click the button below to view the snapshot at that time.

​ 有时我们在做开发的时候需要记录每个任务执行时间,或者记录一段代码执行时间,最简单的方法就是打印当前时间与执行完时间的差值,然后这样如果执行大量测试的话就很麻烦,并且不直观,如果想对执行的时间做进一步控制,则需要在程序中很多地方修改,目前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());
}
}

输出最后一个任务的耗时

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());
}
}

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

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;
}

不同的打印结果

  1. getTotalTimeSeconds() 获取总耗时秒,同时也有获取毫秒的方法
  2. prettyPrint() 优雅的格式打印结果,表格形式
  3. shortSummary() 返回简短的总耗时描述
  4. getTaskCount() 返回统计时间任务的数量
  5. getLastTaskInfo().getTaskName() 返回最后一个任务TaskInfo对象的名称
    更多查看文档

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


About Joyk


Aggregate valuable and interesting links.
Joyk means Joy of geeK