Java Spring 耗时

1、背景

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

示例

先来看几个示例

统计输出总耗时

  1. import org.springframework.util.StopWatch;
  2. public class SpringStopWatchExample {
  3. public static void main (String[] args) throws InterruptedException {
  4. StopWatch sw = new StopWatch();
  5. sw.start();
  6. //long task simulation
  7. Thread.sleep(1000);
  8. sw.stop();
  9. System.out.println(sw.getTotalTimeMillis());
  10. }
  11. }

输出

  1. 1013

输出最后一个任务的耗时

  1. public class SpringStopWatchExample2 {
  2. public static void main (String[] args) throws InterruptedException {
  3. StopWatch sw = new StopWatch();
  4. sw.start("A");//setting a task name
  5. //long task simulation
  6. Thread.sleep(1000);
  7. sw.stop();
  8. System.out.println(sw.getLastTaskTimeMillis());
  9. }
  10. }

输出

  1. 1009

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

  1. import org.springframework.util.StopWatch;
  2. public class SpringStopWatchExample3 {
  3. public static void main (String[] args) throws InterruptedException {
  4. StopWatch sw = new StopWatch();
  5. sw.start("A");
  6. Thread.sleep(500);
  7. sw.stop();
  8. sw.start("B");
  9. Thread.sleep(300);
  10. sw.stop();
  11. sw.start("C");
  12. Thread.sleep(200);
  13. sw.stop();
  14. System.out.println(sw.prettyPrint());
  15. }
  16. }

输出

  1. StopWatch '': running time (millis) = 1031
  2. -----------------------------------------
  3. ms % Task name
  4. -----------------------------------------
  5. 00514 050% A
  6. 00302 029% B
  7. 00215 021% C:

序列服务输出耗时信息

  1. @Override
  2. public long nextSeq(String name) {
  3. StopWatch watch = new StopWatch();
  4. watch.start("单序列获取总消耗");
  5. long sequence = generator.generateId(name);
  6. watch.stop();
  7. logger.info(watch.prettyPrint());
  8. return sequence;
  9. }

2、更多用法

不同的打印结果

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

更多查看文档
https://docs.spring.io/spring-framework/docs/current/javadoc-api/org/springframework/util/StopWatch.html

3、总结

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