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

最新推荐文章于 2024-08-18 03:09:55 发布
最新推荐文章于 2024-08-18 03:09:55 发布
阅读量4.7k 收藏 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


飞鱼武士
关注
专栏目录
Spring Boot入门(23)：记录接口日志再也不难！用AOP和自定义注解给Spring Boot加上日志拦截器！
**My Coding Family**
09-01 3395
基于AOP实现自定义注解拦截接口日志并入库，一文教会你。
浅谈Java项目日志记录和分析 (基于log4j介绍)
且听风吟_z
10-03 7439
一、前言 日志记录是应用程序运行中必不可少的一部分。具有良好格式和完备信息的日志记录可以在程序出现问题时帮助开发人员迅速地定位错误的根源。记录日志只是有效地利用日志的第一步，更重要的是如何对程序运行时产生的日志进行处理和分析。
手把手教你实现一个方法耗时统计的 java agent
微信-sjk6070
03-17 293
前面有两篇铺垫博文，在博文《200303-如何优雅的在 java 中统计代码块耗时》，其最后提到了根据利用 java agent 来统计方法耗时 博文《200316-IDEA + maven 零基础构建 java agent 项目》中则详细描述了搭建一个 java agent 开发测试项目的全过程 本篇博文将进入 java agent 的实战，手把手教你如何是实现一个统计方法耗时的 java ag...
Java记录程序运行耗时
HurryBen的博客
11-05 942
记录一个tips，记录程序运行时间。 public static void main(String[] args) { long start,end; start = System.currentTimeMillis(); /** 被测时程序主体 */ end = System.currentTimeMillis(); System.out.println("耗费时间：" +
Java打印耗时 秒
最新发布
weixin_36764493的博客
08-18 65
我整理的一些关于【Java】的项目学习资料（附讲解～～）和大家一起分享、学习一下：https://d.51cto.com/f2PFnNJava打印耗时秒的简要指南 在Java编程中，精准地测量代码执行的时间是一个常见的需求。尤其在性能调优和代码分析过程中，我们需要帮助开发者更好地理解它们的代码在运行中的性能表现。本篇文章...
打印java代码执行时间
weixin_43526092的博客
03-22 7835
// 代码运行开始时间 Long startTime = System.currentTimeMillis(); // 代码运行结束时间 Long endTime = System.currentTimeMillis(); // 计算并打印耗时 Long tempTime = (endTime - startTime); log.info("开支单列表查询花费时间："+ (((tempTime/86400000)>0)?((tempTime/86400000)+"d"):
java 时间消耗_JavaScript 的时间消耗
weixin_39616071的博客
02-26 111
原标题：JavaScript 的时间消耗随着我们的网站越来越依赖 Java, 我们有时会(无意)用一些不易追踪的方式来传输一些(耗时的)东西. 在这篇文章中, 我会介绍一些能让你的网站在移动设备上快速加载且可交互的方式.摘要: 更少的代码 = 更少的解析/编译(时间) + 更少的传输(时间) + 更少的解压(时间)网络大多数开发者考虑 Java 的时间消耗时, 都会首先考虑到 Java 的下载和执...
14.耗时命令日志
秀才的专栏
08-24 1133
 耗时命令日志： 当一条命令执行时间超过限制时，Redis会将该命令的执行时间等信息加入耗时命令日志（slow log）以供开发查看。 可以通过配置文件的slowlog-log-slower-than参数设置这一限制，单位是微妙（1000000微秒=1秒），默认值是10000。 耗时命令日志存储在内存中，可以通过配置文件的slowlog-max-len参数来限制记录
SpringBoot异步方法捕捉异常详解
08-25
在Spring Boot应用中，异步方法的使用可以显著提高应用程序的性能，因为它允许非阻塞操作，让主线程不等待耗时的操作完成。然而，当这些异步方法抛出异常时，传统的异常处理机制不再适用，因为它们发生在不同的线程...
Java实现按行分割大文件
08-19
这个过程可以使用Java语言来实现，本文将详细介绍Java实现按行分割大文件的方法。 知识点1: Java中读取大文件的方法 Java中有多种方法可以读取大文件，例如使用FileInputStream、BufferedReader等。这里使用...
【日志管理艺术】：Java JAX-WS服务的日志记录与分析策略
## 1.1 日志在Java JAX-WS服务中的作用 Java API for XML Web Services (JAX-WS) 是一种用于创建Web服务的Java API。当开发和维护基于JAX-WS的服务时，系统地记录操作、错误和性能信息至关重要。日志在故障诊断、...
java程序中打运行时间的日志
weixin_42855610的博客
08-14 3716
在程序块开始之前插入 //开始时间 long startTime = System.currentTimeMillis(); 在程序块执行结束之后插入 //结束之间 long endTime = System.currentTimeMillis(); //程序块运行时间 System.out.println("运行时间为：" + (endTime - startTime) + "毫秒") ...
java日志记录接口操作耗时存库
weixin_42064863的博客
07-14 180
Java日志记录接口操作耗时存库 在开发过程中，我们经常需要记录接口操作的耗时情况以便进行性能优化和故障排查。本文将介绍如何使用Java代码记录接口操作的耗时并将其存储到数据库中。 1. 思路分析 我们可以通过在接口方法前后记录时间，并计算时间差来获取接口操作的耗时。然后将耗时信息存储到数据库中。为了方便操作，我们可以定义...
StopWatch通过打印日志记录方法耗时
weixin_44248000的博客
01-26 1517
我们经常会碰到这样的情况，当前端同事或者测试向你反应某个接口很慢的时候，我们就需要去排查这里面的那些代码块处理比较慢。这个时候就需要使用到打印日志的方式。 不同的包的StopWatch类方法不一样，但是都大同小异。我目前用的是springframework.util包下的。 public static void main(String[] args) { long start = System.currentTimeMillis(); System.out.println("==========
Java中统计耗时的方法
Alecor的博客
07-10 4280
时间差统计 这种方式是最简单的方法，记录下开始时间，再记录下结束时间，计算时间差即可。 public class TimeDiffTest { public static void main(String[] args) throws InterruptedException { final long startMs = TimeUtils.nowMs(); TimeUnit.SECONDS.sleep(5); // 模拟业务代码 System.o
Java慢SQL日志
weixin_45066053的博客
09-20 288
Java实现格式化打印慢SQL日志的方法详解什么是慢SQL慢SQL是指运行时间较长的SQL语句，通常是因为查询条件或者表结构不合理引起的。慢SQL会导致数据库负载过高，造成系统性能的下降，需要及时处理。为什么要格式化打印慢SQL日志在开发和调试过程中，我们需要定位并优化慢SQL语句。而格式化打印慢SQL日志可以直观地展示出SQL语句的执行过程，方便我们进行分析和优化。如何实现格式化打印慢SQL日志在Java开发中，我们可以通过设置JDBC驱动的参数，将慢SQL语句输出到日志文件中。具体步骤如下：导入JDBC
打印代码块执行时间方案，优雅打印执行耗时日志
亲测，只为展现最完美的有效！
01-15 1982
目的：打印代码块的执行时间 同时支持传入自定义日志消息，可以将时间打印在日志前面和后面 这是一个例子。使用implements AutoCloseable 实现 使用方式看 main 方法 package com.example.demo.utils; import lombok.extern.slf4j.Slf4j; /** * 统计代码块执行时间 * 使用方式看 main 函数 * * @Author XZ.Tan * @Date: 2021/1/15 10:44 * @Ve.
linux系统下java程序输出日志时间慢8小时处理过程
iamstar0的博客
06-09 2316
记一次linux系统下java程序输出日志时间不对问题排查1、先确认一下系统时间是否正常： date -R # 查看系统时区 2、如果系统时间正常，排查一下机器硬件时间： # 查看硬件时间用hwclock或者clock命令 hwclock # 查看系统硬件时间 clock # 查看系统硬件时间 3、如果是硬件时间导致，可以将系统时间写入到硬件中: hwclock -w # 将系统时间写入到硬件时间 4、如果上述还是有问题，日志输出时间还是不正确，那么可是
记一次AWK程序耗时日志分析
szulilin的博客
06-20 1783
AWK简介 awk和grep sed并称为linux三剑客，听名字就很牛逼，grep常用来做关键字匹配，什么 ps -ef | grep ‘XXX’查找进程 ，cat XXX.log | grep '关键字’查找文件的关键字应该是比较常用的了，sed好像没用过，awk以前听别人提到过很久之前使用过一次用来统计访问量，现在忘记的差不多了，这里刚好有一个需求是分析程序的耗时，因为和各种服务都有关联，需...
arthas如何抓取java.xxx方法耗时
03-14
要Arthas抓取Java方法的耗时可以按照以下步骤进行操作： 1. 首先，确保已经安装了Arthas。你可以从Arthas的官方网站（https://arthas.aliyun.com/）下载并安装它。 2. 打开终端或命令行窗，并进入Arthas的安装目。...
评论
添加红包

请填写红包祝福语或标题

个

红包个数最小为10个

元

红包金额最低5元

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

抵扣说明：

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

余额充值