JAVA方法执行耗时分析工具：Arthas（阿尔萨斯）

目录

介绍

背景

Arthas（阿尔萨斯）能为你做什么？

快速入门

1、启动 math-game

2、启动 Arthas

3、trace命令

介绍

Arthas 是一款线上监控诊断产品，通过全局视角实时查看应用 load、内存、gc、线程的状态信息，并能在不修改应用代码的情况下，对业务问题进行诊断，包括查看方法调用的出入参、异常，监测方法执行耗时，类加载信息等，大大提升线上问题排查效率。

背景

通常，本地开发环境无法访问生产环境。如果在生产环境中遇到问题，则无法使用 IDE 远程调试。更糟糕的是，在生产环境中调试是不可接受的，因为它会暂停所有线程，导致服务暂停。

开发人员可以尝试在测试环境或者预发环境中复现生产环境中的问题。但是，某些问题无法在不同的环境中轻松复现，甚至在重新启动后就消失了。

如果您正在考虑在代码中添加一些日志以帮助解决问题，您将必须经历以下阶段：测试、预发，然后生产。这种方法效率低下，更糟糕的是，该问题可能无法解决，因为一旦 JVM 重新启动，它可能无法复现，如上文所述。

Arthas 旨在解决这些问题。开发人员可以在线解决生产问题。无需 JVM 重启，无需代码更改。 Arthas 作为观察者永远不会暂停正在运行的线程。

Arthas（阿尔萨斯）能为你做什么？

Arthas 是 Alibaba 开源的 Java 诊断工具，深受开发者喜爱。

当你遇到以下类似问题而束手无策时，Arthas可以帮助你解决：

1. 这个类从哪个 jar 包加载的？为什么会报各种类相关的 Exception？
2. 我改的代码为什么没有执行到？难道是我没 commit？分支搞错了？
3. 遇到问题无法在线上 debug，难道只能通过加日志再重新发布吗？
4. 线上遇到某个用户的数据处理有问题，但线上同样无法 debug，线下无法重现！
5. 是否有一个全局视角来查看系统的运行状况？
6. 有什么办法可以监控到 JVM 的实时运行状态？
7. 怎么快速定位应用的热点，生成火焰图？
8. 怎样直接从 JVM 内查找某个类的实例？

Arthas 支持 JDK 6+，支持 Linux/Mac/Windows，采用命令行交互模式，同时提供丰富的 Tab 自动补全功能，进一步方便进行问题的定位和诊断

快速入门

1、启动 math-game

curl -O https://arthas.aliyun.com/math-game.jar
java -jar math-game.jar

math-game是一个简单的程序，每隔一秒生成一个随机数，再执行质因数分解，并打印出分解结果。

math-game源代码：

2、启动 Arthas

在命令行下面执行（使用和目标进程一致的用户启动，否则可能 attach 失败）：

curl -O https://arthas.aliyun.com/arthas-boot.jar
java -jar arthas-boot.jar

• 执行该程序的用户需要和目标进程具有相同的权限。比如以admin用户来执行：sudo su admin && java -jar arthas-boot.jar 或 sudo -u admin -EH java -jar arthas-boot.jar。
• 如果 attach 不上目标进程，可以查看~/logs/arthas/ 目录下的日志。
• 如果下载速度比较慢，可以使用 aliyun 的镜像：java -jar arthas-boot.jar --repo-mirror aliyun --use-http
• java -jar arthas-boot.jar -h 打印更多参数信息。

选择应用 java 进程：

$ $ java -jar arthas-boot.jar
* [1]: 35542
  [2]: 71560 math-game.jar

math-game进程是第 2 个，则输入 2，再输入回车/enter。Arthas 会 attach 到目标进程上，并输出日志：

[INFO] Try to attach process 71560
[INFO] Attach process 71560 success.
[INFO] arthas-client connect 127.0.0.1 3658
  ,---.  ,------. ,--------.,--.  ,--.  ,---.   ,---.
 /  O  \ |  .--. ''--.  .--'|  '--'  | /  O  \ '   .-'
|  .-.  ||  '--'.'   |  |   |  .--.  ||  .-.  |`.  `-.
|  | |  ||  |\  \    |  |   |  |  |  ||  | |  |.-'    |
`--' `--'`--' '--'   `--'   `--'  `--'`--' `--'`-----'


wiki: https://arthas.aliyun.com/doc
version: 3.0.5.20181127201536
pid: 71560
time: 2018-11-28 19:16:24

$

3、trace命令

 格式：trace com.xxx.xxx.TestServiceImp selectGoods

        tracel 包名.类名 方法名

[arthas@1007308]$ trace org.haier.shopUpdate.service.GoodsServiceImpl queryGoodsMessage
Press Q or Ctrl+C to abort.
Affect(class count: 1 , method count: 1) cost in 325 ms, listenerId: 3
`---ts=2024-03-02 08:55:54;thread_name=http-nio-83-exec-4;id=40;is_daemon=true;priority=5;TCCL=org.apache.catalina.loader.ParallelWebappClassLoader@52e6fdee
    `---[1836.141754ms] org.haier.shopUpdate.service.GoodsServiceImpl:queryGoodsMessage()
        +---[0.00% 0.018972ms ] org.haier.shopUpdate.util.ShopsResult:<init>() #162
        +---[3.05% 55.913055ms ] org.haier.shopUpdate.dao.GoodsDao:queryGoodsMessage() #163
        +---[96.90% min=81.296455ms,max=161.912424ms,total=1779.231592ms,count=20] org.haier.shopUpdate.service.GoodsServiceImpl:isImagesTrue() #166
        +---[0.00% 0.026313ms ] org.haier.shopUpdate.util.ShopsResult:setStatus() #171
        +---[0.00% 0.013808ms ] org.haier.shopUpdate.util.ShopsResult:setPageIndex() #172
        +---[0.00% 0.006952ms ] org.haier.shopUpdate.util.ShopsResult:setPageSize() #173
        `---[0.00% 0.017992ms ] org.haier.shopUpdate.util.ShopsResult:setData() #174

session (2cdc29eb-53a7-40be-979c-2a46ee40d32a) is closed because server is going to shutdown.
root@ecs-23868076-002:/mnt/myData/tomcat#