正如标题，我们打印某些步骤或者方法的执行时间是因为程序执行这个方法时花费了较长的时间，但是我们不知道‘罪魁祸首’是哪一条语句。
以前的做法
以前的我为了找到哪条语句执行时间最长是这么做的：
//注释掉逻辑代码
final long begin = System.currentTimeMillis();
//Connection con = session.getConnection();
log.error(excelPath + "--->insert_01_" + (System.currentTimeMillis() - begin));
//con.setAutoCommit(false);
//Statement stat = con.createStatement();
//StringBuilder sb = new StringBuilder();
//sb.append("TRUNCATE `").append(dataInfos.getSheetName()).append("`;");
//stat.addBatch("TRUNCATE `" + dataInfos.getSheetName() + "`");
log.error(excelPath + "--->insert_02_" + (System.currentTimeMillis() - begin));
//String[][] datas = dataInfos.getDatas();
////删除无关代码
//try {
log.error(excelPath + "--->insert_03_" + (System.currentTimeMillis() - begin));
// stat.executeBatch();
log.error(excelPath + "--->insert_04_" + (System.currentTimeMillis() - begin));
//} catch (BatchUpdateException ex) {
上面那段代码的执行结果就不展示了，一句话，就是看着比较乱，下面要介绍的是Spring框架的一个工具类StopWatch，看看它如何优雅的展示执行时间
更优雅的办法（StopWatch）
直接看代码，看看如何使用StopWatch
//注释掉逻辑代码
StopWatch stopWatch = new StopWatch();
//SqlSession session = GetFactory().getSessionFactory().openSession();
stopWatch.start("getConnection");
//Connection con = session.getConnection();
stopWatch.stop();
//Statement stat = con.createStatement();
//
stopWatch.start("addBatch");
//StringBuilder sb = new StringBuilder();
//sb.append("TRUNCATE `").append(dataInfos.getSheetName()).append("`;");
//
//stat.addBatch("TRUNCATE `" + dataInfos.getSheetName() + "`");
//String[][] datas = dataInfos.getDatas();
stopWatch.stop();
//
//log.info(sb.toString());
//try{
stopWatch.start("executeBatch");
// stat.executeBatch();
stopWatch.stop();
//这儿打印结果
log.error(stopWatch.prettyPrint());
//} catch (BatchUpdateException ex)
打印结果
running time (millis) = 555
-----------------------------------------
ms % Task name
-----------------------------------------
00000 000% getConnection
00000 000% addBatch
00555 100% executeBatch
总结
对比以前的做法和使用Spring的StopWatch来打印日志
- 代码的编写来看个人觉得没有省下多少
- 但是展示结果来看无疑
StopWatch,看着优雅舒服直观多了。
