太原市建设工程招投标信息网站,做网站为什么要租服务器,上海有哪几家做新房的网站,微信小程序项目开发实战目录 一、命令介绍二、测试Demo三、命令使用示例3.1、monitor 命令3.1.1、监控primeFactors方法调用情况#xff08;5秒一个周期#xff0c;每过5秒将这5秒统计的信息输出#xff09;3.1.2、监控primeFactors方法调用情况#xff08;5秒一个周期#xff0c;每过5秒将这5秒… 目录 一、命令介绍二、测试Demo三、命令使用示例3.1、monitor 命令3.1.1、监控primeFactors方法调用情况5秒一个周期每过5秒将这5秒统计的信息输出3.1.2、监控primeFactors方法调用情况5秒一个周期每过5秒将这5秒统计的信息输出一共输出两次3.1.3、计算条件表达式过滤统计结果(方法执行完毕之前)3.1.4、计算条件表达式过滤统计结果(方法执行完毕之后) 3.2、watch 命令3.2.1、观察函数调用返回时的参数、this 对象和返回值3.2.2、同时观察函数调用前和函数返回后3.2.3、条件表达式的例子3.2.4、观察异常信息的例子3.2.5、按照耗时进行过滤3.2.6、观察当前对象中的属性 3.3、trace 命令3.3.1、指定监控次数查看执行耗时和调用链路情况3.3.2、包含 jdk 的函数查看执行耗时和调用链路情况3.3.3、根据调用耗时过滤3.3.4、trace 多个类或者多个函数 一、命令介绍
命令说明monitor方法执行监控可以对指定类的方法进行实时监控(给定时间内调用次数、成功次数、失败次数、平均 RT等watch方法执行数据观测可以观察到方法调用的入参、返参等trace方法内部调用路径并输出方法路径上的每个节点上耗时相当于本地链路追踪 这些命令都通过字节码增强技术来实现的会在指定类的方法中插入一些切面来实现数据统计和观测因此在线上、预发使用时请尽量明确需要观测的类、方法以及条件诊断结束要执行 stop 或将增强过的类执行 reset 命令。 官网地址https://arthas.gitee.io/doc
二、测试Demo Arthas官网提供了一个测试Demo这里我稍微调整了一下添加了一个内部内用于测试官网提供了jar包和源码我比较喜欢直接跑源码源码在github我这里把代码粘贴过来避免访问不了将测试Demo运行起来使用arthas-boot.jar连接上准备开始命令使用。
import java.util.ArrayList;
import java.util.List;
import java.util.Random;
import java.util.concurrent.TimeUnit;public class MathGame {private static Random random new Random();private int illegalArgumentCount 0;public static void main(String[] args) throws InterruptedException {MathGame game new MathGame();while (true) {game.run();TimeUnit.SECONDS.sleep(1);}}public void run() throws InterruptedException {try {int number random.nextInt()/10000;ListInteger primeFactors primeFactors(number);print(number, primeFactors);} catch (Exception e) {System.out.println(String.format(illegalArgumentCount:%3d, , illegalArgumentCount) e.getMessage());}}public static void print(int number, ListInteger primeFactors) {StringBuffer sb new StringBuffer(number );for (int factor : primeFactors) {sb.append(factor).append(*);}if (sb.charAt(sb.length() - 1) *) {sb.deleteCharAt(sb.length() - 1);}System.out.println(sb);}public ListInteger primeFactors(int number) {System.out.println(当前时间戳new DateTimeUtils().getNow());if (number 2) {illegalArgumentCount;throw new IllegalArgumentException(number is: number , need 2);}ListInteger result new ArrayListInteger();int i 2;while (i number) {if (number % i 0) {result.add(i);number number / i;i 2;} else {i;}}return result;}class DateTimeUtils {public String getNow() {return String.valueOf(System.currentTimeMillis());}}
}三、命令使用示例
3.1、monitor 命令 monitor方法执行监控是一个很简单好用的线上方法调用响应情况观察工具可以对指定类的方法进行实时监控当线上某个业务响应慢时可以使用monitor对这个方法进行监控查看平均耗时但是monitor没有监控整个链路每一步的处理时间的能力有需要可以使用trace命令。
监控的维度说明
监控项说明timestamp时间戳classJava 类method方法构造方法、普通方法total调用次数success成功次数fail失败次数rt平均 RTfail-rate失败率
参数说明 方法拥有一个命名参数 [c:]意思是统计周期cycle of output拥有一个整型的参数值
参数名称参数说明class-pattern类名表达式匹配支持通配符*method-pattern方法名表达式匹配支持通配符*condition-express条件表达式[E]开启正则表达式匹配默认为通配符匹配[c:]统计周期默认值为 120 秒[b]在方法调用之前计算 condition-express[m ]指定 Class 最大匹配数量默认值为 50。长格式为[maxMatch ] 因为类名支持通配符所以可能会匹配多个类可以用-m限制[n:]]指定统计周期次数
3.1.1、监控primeFactors方法调用情况5秒一个周期每过5秒将这5秒统计的信息输出
我这里收集了3次使用CtrlC退出收集
[arthas9336]$ monitor -c 5 demo.MathGame primeFactors
Press CtrlC to abort.
Affect(class count: 1 , method count: 1) cost in 23 ms, listenerId: 1 timestamp class method total success fail avg-rt(ms) fail-rate
-----------------------------------------------------------------------------------------------2024-01-04 21:12:00 demo.MathGame primeFactors 5 1 4 0.25 80.00%timestamp class method total success fail avg-rt(ms) fail-rate
-----------------------------------------------------------------------------------------------2024-01-04 21:12:05 demo.MathGame primeFactors 5 1 4 0.03 80.00%timestamp class method total success fail avg-rt(ms) fail-rate
-----------------------------------------------------------------------------------------------2024-01-04 21:12:10 demo.MathGame primeFactors 5 4 1 0.66 20.00%3.1.2、监控primeFactors方法调用情况5秒一个周期每过5秒将这5秒统计的信息输出一共输出两次
[arthas9336]$ monitor -c 5 -n 2 demo.MathGame primeFactors
Press CtrlC to abort.
Affect(class count: 1 , method count: 1) cost in 23 ms, listenerId: 2 timestamp class method total success fail avg-rt(ms) fail-rate
-----------------------------------------------------------------------------------------------2024-01-04 21:28:50 demo.MathGame primeFactors 5 4 1 0.73 20.00%timestamp class method total success fail avg-rt(ms) fail-rate
-----------------------------------------------------------------------------------------------2024-01-04 21:28:55 demo.MathGame primeFactors 5 5 0 0.11 0.00%Command execution times exceed limit: 2, so command will exit. You can set it with -n option.3.1.3、计算条件表达式过滤统计结果(方法执行完毕之前)
这个方法执行完毕之前的意思是会先判断入参是否小于等于2如果小于等于2才记录统计不然不记录因为入参小于2会抛出异常所以这里很大概率都是会失败的默认是在方法执行完毕之后判断表达式。
[arthas9336]$ monitor -c 5 -n 3 -b demo.MathGame primeFactors params[0] 2
Press CtrlC to abort.
Affect(class count: 1 , method count: 1) cost in 23 ms, listenerId: 3 timestamp class method total success fail avg-rt(ms) fail-rate
-----------------------------------------------------------------------------------------------2024-01-04 21:39:03 demo.MathGame primeFactors 2 0 2 0.08 100.00%timestamp class method total success fail avg-rt(ms) fail-rate
-----------------------------------------------------------------------------------------------2024-01-04 21:39:08 demo.MathGame primeFactors 3 0 3 0.02 100.00%timestamp class method total success fail avg-rt(ms) fail-rate
-----------------------------------------------------------------------------------------------2024-01-04 21:39:13 demo.MathGame primeFactors 2 0 2 0.03 100.00%Command execution times exceed limit: 3, so command will exit. You can set it with -n option.3.1.4、计算条件表达式过滤统计结果(方法执行完毕之后)
这个好像没有效果按照我的理解应该是primeFactors方法执行完成后进行条件表达式判断只取出入参小于等于2的记录做统计但是这里还是取出了全部数据
[arthas9336]$ monitor -c 5 -n 3 demo.MathGame primeFactors params[0] 2
Press CtrlC to abort.
Affect(class count: 1 , method count: 1) cost in 23 ms, listenerId: 4 timestamp class method total success fail avg-rt(ms) fail-rate
-----------------------------------------------------------------------------------------------2024-01-04 21:45:51 demo.MathGame primeFactors 5 2 3 0.67 60.00%timestamp class method total success fail avg-rt(ms) fail-rate
-----------------------------------------------------------------------------------------------2024-01-04 21:45:56 demo.MathGame primeFactors 5 0 5 0.24 100.00%timestamp class method total success fail avg-rt(ms) fail-rate
-----------------------------------------------------------------------------------------------2024-01-04 21:46:01 demo.MathGame primeFactors 5 4 1 0.31 20.00%Command execution times exceed limit: 3, so command will exit. You can set it with -n option.3.2、watch 命令 watch让你能方便的观察到指定函数的调用情况。能观察到的范围为返回值、抛出异常、入参通过编写 OGNL 表达式进行对应变量的查看。
参数说明 watch 的参数比较多主要是因为它能在 4 个不同的场景观察对象
参数名称参数说明class-pattern类名表达式匹配method-pattern函数名表达式匹配express观察表达式默认值{params, target, returnObj}condition-express条件表达式[b]在函数调用之前观察[e]在函数异常之后观察[s]在函数返回之后观察[f]在函数结束之后(正常返回和异常返回)观察[E]开启正则表达式匹配默认为通配符匹配[x:]指定输出结果的属性遍历深度默认为 1最大值是 4[m ]指定 Class 最大匹配数量默认值为 50。长格式为[maxMatch ][n:]命令执行次数#cost方法执行耗时
这里重点要说明的是观察表达式观察表达式的构成主要由 ognl 表达式组成所以你可以这样写{params,returnObj}只要是一个合法的 ognl 表达式都能被正常支持。
特别说明 watch 命令定义了 4 个观察事件点即 -b 函数调用前-e 函数异常后-s 函数返回后-f 函数结束后4 个观察事件点 -b、-e、-s 默认关闭-f 默认打开当指定观察点被打开后在相应事件点会对观察表达式进行求值并输出 这里要注意函数入参和函数出参的区别有可能在中间被修改导致前后不一致除了 -b 事件点 params 代表函数入参外其余事件都代表函数出参当使用 -b 时由于观察事件点是在函数调用前此时返回值或异常均不存在在 watch 命令的结果里会打印出location信息。location有三种可能值AtEnterAtExitAtExceptionExit。对应函数入口函数正常 return函数抛出异常。
3.2.1、观察函数调用返回时的参数、this 对象和返回值
[arthas3764]$ watch demo.MathGame primeFactors -x 2 -n 1
Press Q or CtrlC to abort.
Affect(class count: 1 , method count: 1) cost in 20 ms, listenerId: 2
methoddemo.MathGame.primeFactors locationAtExit
ts2024-01-08 20:58:31; [cost1.901ms] resultArrayList[ Object[][ Integer[1], ], MathGame[ randomRandom[java.util.Random28c97a5], illegalArgumentCountInteger[53], ], ArrayList[ Integer[2], Integer[95539], ],
]
Command execution times exceed limit: 1, so command will exit. You can set it with -n option.观察表达式默认值是{params, target, returnObj}-x表示遍历深度可以调整来打印具体的参数和结果内容默认值是 1。-x最大值是 4防止展开结果占用太多内存。用户可以在ognl表达式里指定更具体的 field。-n 1代表执行一次就退出-n这个参数在每个Arthas命令中基本都有只是没有写出来而已
3.2.2、同时观察函数调用前和函数返回后
[arthas3764]$ watch demo.MathGame primeFactors {params,target,returnObj} -x 2 -b -s -n 2
Press Q or CtrlC to abort.
Affect(class count: 1 , method count: 1) cost in 18 ms, listenerId: 10
methoddemo.MathGame.primeFactors locationAtEnter
ts2024-01-08 21:09:35; [cost0.0407ms] resultArrayList[ Object[][ Integer[132070], ], MathGame[ randomRandom[java.util.Random28c97a5], illegalArgumentCountInteger[402], ], null,
]
methoddemo.MathGame.primeFactors locationAtExit
ts2024-01-08 21:09:35; [cost2151892.1309ms] resultArrayList[ Object[][ Integer[1], ], MathGame[ randomRandom[java.util.Random28c97a5], illegalArgumentCountInteger[402], ], ArrayList[ Integer[2], Integer[5], Integer[47], Integer[281], ],
]
Command execution times exceed limit: 2, so command will exit. You can set it with -n option. 参数里-n 2表示只执行两次 这里输出结果中第一次输出的是函数调用前的观察表达式的结果第二次输出的是函数返回后的表达式的结果 结果的输出顺序和事件发生的先后顺序一致和命令中 -s -b 的顺序无关
3.2.3、条件表达式的例子
[arthas3764]$ watch demo.MathGame primeFactors {params[0],target} params[0]0 -n 2
Press Q or CtrlC to abort.
Affect(class count: 1 , method count: 1) cost in 16 ms, listenerId: 14
methoddemo.MathGame.primeFactors locationAtExceptionExit ts2024-01-08 21:18:02; [cost0.3214ms] resultArrayList[ Integer[-181424], MathGame[demo.MathGame6659c656],
]
methoddemo.MathGame.primeFactors locationAtExceptionExit
ts2024-01-08 21:18:03; [cost0.0878ms] resultArrayList[ Integer[-202266], MathGame[demo.MathGame6659c656],
]
Command execution times exceed limit: 2, so command will exit. You can set it with -n option.params[0]0 当第一个参数小于0是响应
3.2.4、观察异常信息的例子
[arthas3764]$ watch demo.MathGame primeFactors {params[0],throwExp} -e -x 2 -n 1
Press Q or CtrlC to abort.
Affect(class count: 1 , method count: 1) cost in 17 ms, listenerId: 17
methoddemo.MathGame.primeFactors locationAtExceptionExit
ts2024-01-08 21:24:17; [cost0.3626ms] resultArrayList[ Integer[-212025], java.lang.IllegalArgumentException: number is: -212025, need 2 at demo.MathGame.primeFactors(MathGame.java:47) at demo.MathGame.run(MathGame.java:24) at demo.MathGame.main(MathGame.java:16)
,
]
Command execution times exceed limit: 1, so command will exit. You can set it with -n option. -e表示抛出异常时才触发express 观察表达式中表示异常信息的变量是throwExp
3.2.5、按照耗时进行过滤
[arthas3764]$ watch demo.MathGame primeFactors {params, returnObj} #cost0.5 -x 2 -n 1
Press Q or CtrlC to abort.
Affect(class count: 1 , method count: 1) cost in 19 ms, listenerId: 22
methoddemo.MathGame.primeFactors locationAtExit
ts2024-01-08 21:30:26; [cost1.1834ms] resultArrayList[ Object[][ Integer[1], ], ArrayList[ Integer[2], Integer[63347], ],
]
Command execution times exceed limit: 1, so command will exit. You can set it with -n option. #cost0.5(单位是ms)表示只有当耗时大于 0.5ms 时才会输出过滤掉执行时间小于 0.5ms 的调用
3.2.6、观察当前对象中的属性
[arthas3764]$ watch demo.MathGame primeFactors {params,target.illegalArgumentCount} -x 2 -n 1
Press Q or CtrlC to abort.
Affect(class count: 1 , method count: 1) cost in 19 ms, listenerId: 26
methoddemo.MathGame.primeFactors locationAtExit
ts2024-01-08 21:34:00; [cost0.2875ms] resultArrayList[ Object[][ Integer[1], ], Integer[1149],
]
Command execution times exceed limit: 1, so command will exit. You can set it with -n option.可以使用target.field_name访问当前对象的某个属性target相当于当前对象
3.3、trace 命令 trace 命令能监控方法内部调用路径并输出方法路径上的每个节点上耗时方便的帮助定位和发现因 RT 高而导致的性能问题缺陷trace 命令能主动搜索 class-patternmethod-pattern 对应的方法调用路径渲染和统计整个调用链路上的所有性能开销和追踪调用链路。
参数说明
参数名称参数说明class-pattern类名表达式匹配支持通配符*method-pattern方法名表达式匹配支持通配符*condition-express条件表达式[E]开启正则表达式匹配默认为通配符匹配[n:]命令执行次数#cost方法执行耗时[m ]指定 Class 最大匹配数量默认值为 50。长格式为[maxMatch ] 因为类名支持通配符所以可能会匹配多个类可以用-m限制–skipJDKMethod 是否包含 jdk 的函数默认false不显示jdk函数调用 这里重点要说明的是条件表达式条件表达式的构成主要由 ognl 表达式组成所以你可以这样写params[0]0只要是一个合法的 ognl 表达式都能被正常支持。 很多时候我们只想看到某个方法的 RT 大于某个时间之后的 trace 结果现在 Arthas 可以按照方法执行的耗时来进行过滤了例如trace *StringUtils isBlank #cost100’表示当执行时间超过 100ms 的时候才会输出 trace 的结果。 注意事项 trace 监控后看到的结果时间可能不准确总耗时看到的是1000ms但是打印的链路耗时加起来只有500ms这是因为有些类和函数没有被 trace 到或者非函数调用的指令消耗。比如 i, getfield等指令。又或者是在代码执行过程中JVM 可能出现停顿比如 GC进入同步块等。
3.3.1、指定监控次数查看执行耗时和调用链路情况
监控 demo.MathGame 类中 run() 方法执行耗时和调用链路情况可以通过-n 指定监控次数不指定会一直监控
[arthas3432]$ trace demo.MathGame run -n 3
Press Q or CtrlC to abort.
Affect(class count: 1 , method count: 1) cost in 16 ms, listenerId: 3
---ts2024-01-08 16:10:05;thread_namemain;id1;is_daemonfalse;priority5;TCCLsun.misc.Launcher$AppClassLoader18b4aac2 ---[0.4212ms] demo.MathGame:run() ---[18.71% 0.0788ms ] demo.MathGame:primeFactors() #24 ---[48.98% 0.2063ms ] demo.MathGame:print() #25 ---ts2024-01-08 16:10:06;thread_namemain;id1;is_daemonfalse;priority5;TCCLsun.misc.Launcher$AppClassLoader18b4aac2 ---[0.518ms] demo.MathGame:run() ---[25.14% 0.1302ms ] demo.MathGame:primeFactors() #24 [throws Exception] ---ts2024-01-08 16:10:07;thread_namemain;id1;is_daemonfalse;priority5;TCCLsun.misc.Launcher$AppClassLoader18b4aac2 ---[0.3005ms] demo.MathGame:run() ---[28.15% 0.0846ms ] demo.MathGame:primeFactors() #24 [throws Exception]Command execution times exceed limit: 3, so command will exit. You can set it with -n option.3.3.2、包含 jdk 的函数查看执行耗时和调用链路情况
监控 demo.MathGame 类中 run() 方法执行耗时和调用链路情况包含 jdk 的函数
[arthas5848]$ trace --skipJDKMethod false demo.MathGame run
Press Q or CtrlC to abort.
Affect(class count: 1 , method count: 1) cost in 42 ms, listenerId: 7
---ts2024-01-08 16:33:53;thread_namemain;id1;is_daemonfalse;priority5;TCCLsun.misc.Launcher$AppClassLoader18b4aac2 ---[0.8148ms] demo.MathGame:run() ---[8.09% 0.0659ms ] java.util.Random:nextInt() #24 ---[21.94% 0.1788ms ] demo.MathGame:primeFactors() #25 ---[33.21% 0.2706ms ] demo.MathGame:print() #26 ---ts2024-01-08 16:33:54;thread_namemain;id1;is_daemonfalse;priority5;TCCLsun.misc.Launcher$AppClassLoader18b4aac2 ---[0.2696ms] demo.MathGame:run() ---[4.15% 0.0112ms ] java.util.Random:nextInt() #24 ---[32.31% 0.0871ms ] demo.MathGame:primeFactors() #25 ---[34.94% 0.0942ms ] demo.MathGame:print() #26 ---ts2024-01-08 16:33:55;thread_namemain;id1;is_daemonfalse;priority5;TCCLsun.misc.Launcher$AppClassLoader18b4aac2 ---[0.5625ms] demo.MathGame:run() ---[1.37% 0.0077ms ] java.util.Random:nextInt() #24 ---[30.31% 0.1705ms ] demo.MathGame:primeFactors() #25 [throws Exception] ---[1.96% 0.011ms ] java.lang.StringBuilder:init() #29 ---[21.40% 0.1204ms ] java.lang.String:format() #29 ---[2.24% min0.0046ms,max0.008ms,total0.0126ms,count2] java.lang.StringBuilder:append() #29 ---[1.10% 0.0062ms ] java.lang.Exception:getMessage() #29 ---[1.08% 0.0061ms ] java.lang.StringBuilder:toString() #29 ---[12.73% 0.0716ms ] java.io.PrintStream:println() #29 3.3.3、根据调用耗时过滤
监控 demo.MathGame 类中 run() 方法执行耗时和调用链路情况据调用耗时过滤
[arthas5848]$ trace demo.MathGame run #cost 0.5
Press Q or CtrlC to abort.
Affect(class count: 1 , method count: 1) cost in 14 ms, listenerId: 10
---ts2024-01-08 16:40:23;thread_namemain;id1;is_daemonfalse;priority5;TCCLsun.misc.Launcher$AppClassLoader18b4aac2 ---[0.9167ms] demo.MathGame:run() ---[20.29% 0.186ms ] demo.MathGame:primeFactors() #25 ---[37.11% 0.3402ms ] demo.MathGame:print() #26---ts2024-01-08 16:40:27;thread_namemain;id1;is_daemonfalse;priority5;TCCLsun.misc.Launcher$AppClassLoader18b4aac2 ---[1.6932ms] demo.MathGame:run() ---[30.12% 0.51ms ] demo.MathGame:primeFactors() #25 [throws Exception]---ts2024-01-08 16:40:52;thread_namemain;id1;is_daemonfalse;priority5;TCCLsun.misc.Launcher$AppClassLoader18b4aac2 ---[0.5762ms] demo.MathGame:run() ---[77.91% 0.4489ms ] demo.MathGame:primeFactors() #25 ---[14.58% 0.084ms ] demo.MathGame:print() #26 3.3.4、trace 多个类或者多个函数
在MathGame代码中我们还调用了一个内部类DateTimeUtils的getNow()方法外部类也是一样的写出demo.DateTimeUtils即可在链路中并没有输出因为trace 命令只会 trace 匹配到的函数里的子调用并不会向下 trace 多层。因为 trace 是代价比较昂贵的多层 trace 可能会导致最终要 trace 的类和函数非常多。
可以用正则表匹配路径上的多个类和函数一定程度上达到多层 trace 的效果。
[arthas3464]$ trace -E demo.MathGame|demo.MathGame.DateTimeUtils run|getNow
Press Q or CtrlC to abort.
Affect(class count: 2 , method count: 2) cost in 27 ms, listenerId: 8
---ts2024-01-08 17:07:15;thread_namemain;id1;is_daemonfalse;priority5;TCCLsun.misc.Launcher$AppClassLoader18b4aac2 ---[1.5505ms] demo.MathGame:run() ---[63.54% 0.9852ms ] demo.MathGame:primeFactors() #24 [throws Exception] ---[24.59% 0.2423ms ] demo.MathGame$DateTimeUtils:getNow() ---ts2024-01-08 17:07:16;thread_namemain;id1;is_daemonfalse;priority5;TCCLsun.misc.Launcher$AppClassLoader18b4aac2 ---[0.341801ms] demo.MathGame:run() ---[46.96% 0.1605ms ] demo.MathGame:primeFactors() #24 [throws Exception] ---[20.12% 0.0323ms ] demo.MathGame$DateTimeUtils:getNow() ---ts2024-01-08 17:07:17;thread_namemain;id1;is_daemonfalse;priority5;TCCLsun.misc.Launcher$AppClassLoader18b4aac2 ---[0.870299ms] demo.MathGame:run() ---[57.59% 0.5012ms ] demo.MathGame:primeFactors() #24 | ---[3.91% 0.0196ms ] demo.MathGame$DateTimeUtils:getNow() ---[29.53% 0.257ms ] demo.MathGame:print() #25
