JAVA方法耗时信息捕捉并日志

最新推荐文章于 2022-10-13 15:00:39 发布
最新推荐文章于 2022-10-13 15:00:39 发布
阅读量4.6k 收藏 1
点赞数
分类专栏： JAVA 系统设计
版权声明：本文为博主原创文章，遵循 CC 4.0 BY-SA 版权协议，转载请附上原文出处链接和本声明。
本文链接：https://blog.csdn.net/xcwll_sina/article/details/50015309
版权

一. 设计思想：

               1、要保证日志的先后顺序，要保证输出的先后顺序；

               2、其次就是要保证不要出现存储耗时时间的List量太大的情况，所以解决方法是

                              A、使用ThreadLocal，每个线程输出自己的日志，这样比较清晰；

                              B、要做一下MAX_SIZE控制

               3、要做到灵活控制开关，本方法是用了日志的DEBUG级别进行开关控制，当DEBUG时，统计方法耗时信息，不影响线上数据

               4、方法API简单，直接使用record和print方法即可。

二. 使用场合：仅限于单次线程中进行的方法时间捕捉

三. 代码实现：

import org.slf4j.Logger;
import org.slf4j.LoggerFactory;
import java.text.SimpleDateFormat;
import java.util.*;

/**
 * 记录方法执行的时间
 * @author wull
 */
public class MethodTimeUtil {
	/** LOGGER */
	private static final Logger log = LoggerFactory.getLogger(MethodTimeUtil.class);
	/** ThreadLocal缓存 */
	private static ThreadLocal<LinkedHashMap<String,MethodTime>> mts = new ThreadLocal<LinkedHashMap<String,MethodTime>>();
	/** 前缀 */
	private static final String PREFIX = "------->方法执行时间记录		";
	/** 根据日志级别，来开启本功能 */
	private static boolean isDebug = log.isDebugEnabled() ;
	/** 存入list的最大数 */
	private static int MAX_SIZE = 1000;
	/** 输出时间的格式 */
	private static final SimpleDateFormat TIME_FORMAT = new SimpleDateFormat("hh:MM:ss");

	/**
	 * 录入时间数据
	 * @param title
	 * @param isStart
	 */
	public static void record(String title ,boolean isStart){
		if(isDebug == false){
			return ;
		}
		LinkedHashMap<String,MethodTime> map = mts.get();
		if(map == null){
			map = new LinkedHashMap<String,MethodTime>();
		}

		if(map.size() > MAX_SIZE){
			printByParam(false);
		}

		if(isStart){
			map.put(title, new MethodTime(title, isStart));
		}else{
			MethodTime mt = map.get(title);
			if(mt != null && mt.getTitle().equals(title)){
				mt.setStart(false);
				mt.setTime(System.currentTimeMillis() - mt.getTime());
			}else{
				map.put(title, new MethodTime(title, isStart));
			}
		}
		mts.set(map);
	}

	/**
	 * hide
	 * 打印已经录入的数据，并按照条件清空这些数据
	 */
	private static void printByParam(boolean isFinal){
		String preLog = PREFIX + " THREAD["+Thread.currentThread().getName() +"] ";
		if(isDebug == false){
			return ;
		}
		LinkedHashMap<String,MethodTime> map = mts.get();
		if(map == null || map.size() == 0){
			log.debug( preLog + "当前线程没有记录方法时间信息。");
			return ;
		}

		int listInitSize = map.size();
		Iterator iterator = map.entrySet().iterator();
		while(iterator.hasNext()){
			Map.Entry<String, MethodTime> entry = (Map.Entry<String, MethodTime>)iterator.next();
			MethodTime mt = entry.getValue();
			if (mt.isStart() == false){
				log.debug( preLog + mt.getTitle()+"------------>method last:"+mt.getTime()+" MillisSecords");
				iterator.remove();
			}else{
				if(isFinal) {
					log.debug(preLog + mt.getTitle() + "------------->method startTime:" + TIME_FORMAT.format(new Date(mt.getTime())));
				}
			}
		}

		// 如果清理了已经获知时间的时间信息后，仍然大于MAX_SIZE,那就清理所有
		if(isFinal) {
			log.debug( preLog +"print finish,recodList.size=" + listInitSize );
			map.clear();
		}
	}

	/**
	 * open
	 * 打印已经录入的数据，并清空这些数据
	 */
	public static void print(){
		printByParam(true);
	}

	public static void main(String [] arggs){

		MethodTimeUtil.record("测试1：",true);
		MethodTimeUtil.record("测试2：",true);
		MethodTimeUtil.record("测试1：",false);
		MethodTimeUtil.record("测试2：",false);
		MethodTimeUtil.print();

		for(int k= 1;k<5;k++) {
			new Thread("Thread-" + k) {
				public void run() {
					Random r = new Random();
					for (int i = 1; i <= 5; i++) {
						int titleNo  = i;
						String title = titleNo + ".TEST:";
						boolean b = true;
						MethodTimeUtil.record(title, b);
					}
					MethodTimeUtil.print();
				}
			}.start();
		}
	}
}

 class MethodTime  {
	private String title;
	private boolean isStart;
	private long time;
	public MethodTime(String title, boolean isStart) {
		this.title = title;
		this.isStart = isStart;
		this.time = System.currentTimeMillis();
	}

	public String getTitle() {
		return title;
	}
	public void setTitle(String title) {
		this.title = title;
	}
	public boolean isStart() {
		return isStart;
	}
	public void setStart(boolean isStart) {
		this.isStart = isStart;
	}
	public long getTime() {
		return time;
	}
	public void setTime(long time) {
		this.time = time;
	}
}

四. 结果输出： 

Connected to the target VM, address: '127.0.0.1:58681', transport: 'socket'
16:52:34.618 [main] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[main] 测试1：------------>method last:0 MillisSecords
16:52:34.623 [main] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[main] 测试2：------------>method last:0 MillisSecords
16:52:34.624 [main] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[main] print finish,recodList.size=2
16:52:34.625 [Thread-1] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[Thread-1] 1.TEST:------------->method startTime:04:11:34
16:52:34.625 [Thread-1] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[Thread-1] 2.TEST:------------->method startTime:04:11:34
16:52:34.625 [Thread-1] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[Thread-1] 3.TEST:------------->method startTime:04:11:34
16:52:34.625 [Thread-1] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[Thread-1] 4.TEST:------------->method startTime:04:11:34
16:52:34.625 [Thread-1] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[Thread-1] 5.TEST:------------->method startTime:04:11:34
16:52:34.625 [Thread-1] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[Thread-1] print finish,recodList.size=5
16:52:34.626 [Thread-3] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[Thread-3] 1.TEST:------------->method startTime:04:11:34
16:52:34.626 [Thread-3] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[Thread-3] 2.TEST:------------->method startTime:04:11:34
16:52:34.626 [Thread-3] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[Thread-3] 3.TEST:------------->method startTime:04:11:34
16:52:34.626 [Thread-2] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[Thread-2] 1.TEST:------------->method startTime:04:11:34
16:52:34.626 [Thread-3] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[Thread-3] 4.TEST:------------->method startTime:04:11:34
16:52:34.626 [Thread-2] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[Thread-2] 2.TEST:------------->method startTime:04:11:34
16:52:34.626 [Thread-3] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[Thread-3] 5.TEST:------------->method startTime:04:11:34
16:52:34.626 [Thread-2] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[Thread-2] 3.TEST:------------->method startTime:04:11:34
16:52:34.626 [Thread-3] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[Thread-3] print finish,recodList.size=5
16:52:34.626 [Thread-2] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[Thread-2] 4.TEST:------------->method startTime:04:11:34
16:52:34.626 [Thread-2] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[Thread-2] 5.TEST:------------->method startTime:04:11:34
16:52:34.626 [Thread-2] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[Thread-2] print finish,recodList.size=5
16:52:34.636 [Thread-4] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[Thread-4] 1.TEST:------------->method startTime:04:11:34
16:52:34.636 [Thread-4] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[Thread-4] 2.TEST:------------->method startTime:04:11:34
16:52:34.636 [Thread-4] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[Thread-4] 3.TEST:------------->method startTime:04:11:34
16:52:34.636 [Thread-4] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[Thread-4] 4.TEST:------------->method startTime:04:11:34
16:52:34.636 [Thread-4] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[Thread-4] 5.TEST:------------->method startTime:04:11:34
16:52:34.637 [Thread-4] DEBUG n.x.teacheval.utils.MethodTimeUtil - ------->方法执行时间记录		 THREAD[Thread-4] print finish,recodList.size=5
Disconnected from the target VM, address: '127.0.0.1:58681', transport: 'socket'

Process finished with exit code 0


飞鱼武士
关注
  • 0
    点赞
  • 踩
  • 1
    收藏
    觉得还不错? 一键收藏
  • 0
    评论
专栏目录
Java利用spring aop进行监测方法执行耗时
05-25
使用 Spring AOP 进行方法耗时监测的好处有以下几点： 1. 代码实现简单，易于维护：使用 Spring AOP 可以将耗时监测的逻辑与业务逻辑进行解耦，避免业务逻辑代码的冗余和代码维护难度的提高。 2. 安全性高：使用 Spring AOP 进行方法耗时监测，可以在不修改业务逻辑代码的情况下，对业务方法进行耗时监测；避免了因为在业务代码中加入计时逻辑而导致的安全风险。 3. 可扩展性强：使用 Spring AOP 进行方法耗时监测，可以方便的对其他的切面进行扩展，例如：日志、异常处理、权限控制等切面。 4. 提高系统性能：通过对系统中的方法进行耗时监测，可以及时发现系统中的性能瓶颈，从而进行优化；避免了因为性能问题导致的系统运行效率低下和用户体验差的情况。
谈谈 Java 日志
z123456789XDW的博客
08-16 374
点击下方关注我，然后右上角点击...“设为星标”，就能第一时间收到更新推送啦~~~日志：就是介绍一个过程和经历的详细记录。项目日志：就是项目开发过程的详细记录，一般由项目经理记录。代码里的日志：就是程序员记录某个开发过程的详细情况，这是项目里每个程序员需要做的工作。代码里的日志在项目中扮演着非常重要的角色，日志记录的详细程度决定系统是否容易维护，Java 项目中日志记录的框架有很多，在项目中如何选...
Java记录程序运行耗时
HurryBen的博客
11-05 875
记录一个tips，记录程序运行时间。 public static void main(String[] args) { long start,end; start = System.currentTimeMillis(); /** 被测时程序主体 */ end = System.currentTimeMillis(); System.out.println("耗费时间：" +
Java中统计耗时的方法
Alecor的博客
07-10 4133
时间差统计 这种方式是最简单的方法，记录下开始时间，再记录下结束时间，计算时间差即可。 public class TimeDiffTest { public static void main(String[] args) throws InterruptedException { final long startMs = TimeUtils.nowMs(); TimeUnit.SECONDS.sleep(5); // 模拟业务代码 System.o
类当中日志应定义成什么类型_技术分享丨一篇全解Java日志
weixin_35852816的博客
01-20 306
关注我们，IT前沿知识—— 划重点！一、日志简介1.1 日志是什么(WHAT)日志：记录程序的运行轨迹，方便查找关键信息，也方便快速定位解决问题。通常，Java程序员在开发项目时都是依赖Eclipse/IDEA等集成开发工具的Debug 调试功能来跟踪解决Bug，但项目发布到了测试、生产环境怎么办？你有可能会说可以使用远程调试，但实际并不能允许让你这么做。所以，日志的作用就是在测试、生产...
Java统计代码的执行时间的6种方法
热门推荐
Zero .
11-09 1万+
在日常开发中经常需要测试一些代码的执行时间，但又不想使用向 JMH（Java Microbenchmark Harness，Java 微基准测试套件）这么重的测试框架，所以本文就汇总了一些 Java 中比较常用的执行时间统计方法，总共包含以下 6 种，如下图所示： 方法一：System.currentTimeMillis 此方法为 Java 内置的方法，使用 System#currentTimeMillis 来统计执行的时间（统计单位：毫秒），示例代码如下： public class TimeInte
Java日志框架对比分析及性能调优
小老板_BK
03-22 637
文章目录前言一、Logback性能怎么样？1. 整体流程2. 性能解析异步输出锁争抢磁盘输出控制台输出总结 前言 本文针对Log4j2和Logback框架进行深入解析，通过性能压测、热点图及源码方式，分析两者优劣势及产生性能差距原理，在最后总结Log4j2性能调优的方式 一、Logback性能怎么样？ 众所周知，Log4j2 1. 整体流程 日志记录的流程如下： 业务代码内调用Logger记录日志 Logger把日志移交到Appender 同步Appender继续完成输出（落盘、网络提交、控制台输
打印代码块执行时间方案，优雅打印执行耗时日志
亲测，只为展现最完美的有效！
01-15 1896
目的：打印代码块的执行时间 同时支持传入自定义日志消息，可以将时间打印在日志前面和后面 这是一个例子。使用implements AutoCloseable 实现 使用方式看 main 方法 package com.example.demo.utils; import lombok.extern.slf4j.Slf4j; /** * 统计代码块执行时间 * 使用方式看 main 函数 * * @Author XZ.Tan * @Date: 2021/1/15 10:44 * @Ve.
StopWatch通过打印日志记录方法耗时
weixin_44248000的博客
01-26 1308
我们经常会碰到这样的情况，当前端同事或者测试向你反应某个接口很慢的时候，我们就需要去排查这里面的那些代码块处理比较慢。这个时候就需要使用到打印日志的方式。 不同的包的StopWatch类方法不一样，但是都大同小异。我目前用的是springframework.util包下的。 public static void main(String[] args) { long start = System.currentTimeMillis(); System.out.println("==========
Java统计耗时
我的博客
10-13 2565
除了 Spring，hutool 工具库和 Apache common 工具包都提供了各自的 StopWatch。hutool中的 StopWatch 就来自 Spring 的 StopWatch.java，用法也完全一致。除了可以通过局部时间，还可以通过 sw.getTotalTimeSeconds() 获取总的耗时。
java反射耗时测试案例解析
08-25
主要介绍了java反射耗时测试案例解析,文中通过示例代码介绍的非常详细，对大家的学习或者工作具有一定的参考学习价值,需要的朋友可以参考下
Java实现的耗时统计类-可用于测试程序耗时
01-15
Java实现的耗时统计类，可用于平常的测试程序耗时，以及耗时的格式化输出，
JAVA项目日志文件模版
06-15
状态和性能信息：日志文件可以记录应用程序的状态和性能信息，如请求响应时间、数据库查询耗时、资源使用情况等。这些日志对于分析系统性能和优化应用程序非常有帮助。 安全审计：日志文件可以记录用户的操作行为、...
javaagent:基于javaagent开发的APM工具，收集方法的执行次数和执行时间，定时输出成json格式的日志
05-13
相比于NewRelic或者开源的,以及阿里的,本工具主打的是简单，我们只记录每个方法的执行次数和时间，并输出到json格式的日志文件中。基于javaagent的日志，你可以使用严丽同学开发的进行分析查询，或者可以自己去写...
JAVA从url中分离ip和port
xcwll314的专栏
06-10 8453
具体代码： public class NetAddrUtil { /** * 从url中分析出hostIP * @param url * @author wuliangliang * @return */ public static String getIpFromUrl(String url) { // 1.判断是否为空 if (url == null ||
JAVA技术框架选型
xcwll314的专栏
06-16 2206
在做新项目的时候，遇到项目技术选型问题，思考了一下，可以从以下几个方面进行考虑： 1.  技术应用场景   2.  技术可靠性稳定性考虑，该技术是否得到了一定的应用   3. 技术API文档是否完善，与其他框架的性能比较；   4. 是否开源，收费（如果需要进行开源改造）   5. 技术团队的技术氛围，新技术需要能尽量贴合团队成员的一贯开发路线；
common.io中Tailer类读取文件内容乱码问题解决
xcwll314的专栏
08-19 1344
common.io 是一个实用程序库，协助开发IO功能。 org.apache.comons.io.input 包针对SUN JDK IO包进行了扩展，实现了一些功能简单的IO类，主要包括了对字节/字符输入流接口的实现 在项目开发中（斯凯闪电狗-日志监控分析系统），出现了输出中文乱码的问题。具体跟踪到org.apache.comons.io.input.Tailer类中。 原因：通
tup
xcwll314的专栏
05-23 1053
package com.zjapl.erecord.core.common.img; import java.io.File; import java.io.IOException; import java.sql.Timestamp; import java.text.SimpleDateFormat; import java.util.Date; import org.cs
arthas如何抓取java.xxx方法耗时
最新发布
03-14
Arthas是一款Java诊断工具，可以于实时监控和诊断Java应用程序。它提供了丰富的功能，包括抓取方法耗时。 要Arthas抓取Java方法的耗时可以按照以下步骤进行操作： 1. 首先，确保已经安装了Arthas。你可以从Arthas的官方网站（https://arthas.aliyun.com/）下载并安装它。 2. 打开终端或命令行窗，并进入Arthas的安装目。 3. 使用以下命令启动Arthas： ``` ./as.sh ``` 4. 当Arthas启动后，你可以使用`dashboard`命令查看当前应用程序的概览信息： ``` dashboard ``` 5. 使用`trace`命令来抓取方法的耗时。例如，如果要抓取`java.xxx`包下的方法耗时，可以使用以下命令： ``` trace java.xxx.* "{params,target,returnObj}" - 5 ``` 这个命令将会抓取`java.xxx`包下的方法调用，并显示方法的参数、目标对象和返回值。`-n 5`表示只显示最近的5次方法调用。 6. 当你不再需要抓取方法耗时时，可以使用`untrace`命令停止抓取： ``` untrace java.xxx.* ``` 以上就是使用Arthas抓取Java方法耗时的简要介绍。

“相关推荐”对你有帮助么？

  • 非常没帮助
  • 没帮助
  • 一般
  • 有帮助
  • 非常有帮助
提交
评论
添加红包

请填写红包祝福语或标题

个

红包个数最小为10个

元

红包金额最低5元

当前余额3.43元 前往充值 >
需支付：10.00元
成就一亿技术人!
领取后你会自动成为博主和红包主的粉丝 规则
hope_wisdom
发出的红包
实付元
使用余额支付
点击重新获取
扫码支付
钱包余额 0

抵扣说明：

1.余额是钱包充值的虚拟货币，按照1:1的比例进行支付金额的抵扣。
2.余额无法直接购买下载，可以购买VIP、付费专栏及课程。

余额充值