java实战之在线调试Arthas

最新推荐文章于 2024-06-08 17:19:19 发布

老王@上新路

最新推荐文章于 2024-06-08 17:19:19 发布

阅读量744

点赞数 14

分类专栏：云原生 java 文章标签： java 开发语言后端云原生中间件 spring cloud

本文链接：https://blog.csdn.net/pingzhilidexingfu/article/details/134336911

版权

云原生同时被 2 个专栏收录

9 篇文章 0 订阅

订阅专栏

java

6 篇文章 0 订阅

订阅专栏

1.简介

Arthas 是一款线上监控诊断产品，通过全局视角实时查看应用 load、内存、gc、线程的状态信息，并能在不修改应用代码的情况下，对业务问题进行诊断，包括查看方法调用的出入参、异常，监测方法执行耗时，类加载信息等，大大提升线上问题排查效率。
https://arthas.aliyun.com/doc/commands.html#%E5%90%8E%E5%8F%B0%E5%BC%82%E6%AD%A5%E4%BB%BB%E5%8A%A1

2.安装

2.1 直接安装

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

2.2集成到springboot服务
访问地址 http://localhost:8080/actuator/arthas

#pom文件依赖
<dependency>
   <groupId>com.taobao.arthas</groupId>
   <artifactId>arthas-spring-boot-starter</artifactId>
  <version>${arthas.version}</version>
</dependency>

3.常用命令及步骤

找到耗时的线程 thread
找到该线程耗时的方法 trace
反编译该方法进行查看 jad
观察他的入参、返回值、调用次数 watch

3.1 thread 找到线程

(1) 一键展示当前最忙的前 N 个线程并打印堆栈

[arthas@6]$ thread -n 10 # 一键展示当前最忙的前 N 个线程并打印堆栈
"automation-notification-consumer-3-thread-1" Id=69 cpuUsage=0.55% deltaTime=1ms time=1551554ms RUNNABLE (in native)
    at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
    at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
    at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
    at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
    at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
    at org.apache.kafka.common.network.Selector.select(Selector.java:691)
    at org.apache.kafka.common.network.Selector.poll(Selector.java:411)
    at org.apache.kafka.clients.NetworkClient.poll(NetworkClient.java:510)
    at org.apache.kafka.clients.consumer.internals.ConsumerNetworkClient.poll(ConsumerNetworkClient.java:271)
    at org.apache.kafka.clients.consumer.internals.ConsumerNetworkClient.poll(ConsumerNetworkClient.java:242)
    at org.apache.kafka.clients.consumer.KafkaConsumer.pollForFetches(KafkaConsumer.java:1247)
    at org.apache.kafka.clients.consumer.KafkaConsumer.poll(KafkaConsumer.java:1187)
    at org.apache.kafka.clients.consumer.KafkaConsumer.poll(KafkaConsumer.java:1154)
    at cn.my.mind.automation.engine.queue.KafkaConsumerService.doPoll(KafkaConsumerService.java:45)
    at cn.my.mind.automation.engine.queue.NotificationConsumer.lambda$launchNotificationConsumer$1(NotificationConsumer.java:147)
    at cn.my.mind.automation.engine.queue.NotificationConsumer$$Lambda$1039/969502280.run(Unknown Source)
    at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
    at java.util.concurrent.FutureTask.run(FutureTask.java:266)
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
    at java.lang.Thread.run(Thread.java:748)

(2) 找出当前阻塞其他线程的线程
有时候我们发现应用卡住了，通常是由于某个线程拿住了某个锁，并且其他线程都在等待这把锁造成的。为了排查这类问题， arthas 提供了thread -b，一键找出那个罪魁祸首。

[arthas@6]$ thread -b #找出当前阻塞其他线程的线程
"automation-notification-consumer-3-thread-1" Id=69 RUNNABLE
    at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
    at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
    at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
    at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
    -  locked sun.nio.ch.Util$3@51eb8b13
    -  locked java.util.Collections$UnmodifiableSet@12c32ecf
    -  locked sun.nio.ch.EPollSelectorImpl@12f8cab6
    at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
    at org.apache.kafka.common.network.Selector.select(Selector.java:691)
    at org.apache.kafka.common.network.Selector.poll(Selector.java:411)
    at org.apache.kafka.clients.NetworkClient.poll(NetworkClient.java:510)
    at org.apache.kafka.clients.consumer.internals.ConsumerNetworkClient.poll(ConsumerNetworkClient.java:271)
    at org.apache.kafka.clients.consumer.internals.ConsumerNetworkClient.poll(ConsumerNetworkClient.java:242)
    at org.apache.kafka.clients.consumer.KafkaConsumer.pollForFetches(KafkaConsumer.java:1247)
    at org.apache.kafka.clients.consumer.KafkaConsumer.poll(KafkaConsumer.java:1187)
    at org.apache.kafka.clients.consumer.KafkaConsumer.poll(KafkaConsumer.java:1154)
    at cn.my.mind.automation.engine.queue.KafkaConsumerService.doPoll(KafkaConsumerService.java:45)
    at cn.my.mind.automation.engine.queue.NotificationConsumer.lambda$launchNotificationConsumer$1(NotificationConsumer.java:147)
    at cn.my.mind.automation.engine.queue.NotificationConsumer$$Lambda$1039/969502280.run(Unknown Source)
    at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
    at java.util.concurrent.FutureTask.run(FutureTask.java:266)
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
    at java.lang.Thread.run(Thread.java:748)

    Number of locked synchronizers = 2
    - java.util.concurrent.locks.ReentrantLock$FairSync@66be4d80 <---- but blocks 1 other threads!
    - java.util.concurrent.ThreadPoolExecutor$Worker@188598ad

(3) thread --state ，查看指定状态的线程

[arthas@6]$ thread --state WAITING
Threads Total: 16, NEW: 0, RUNNABLE: 9, BLOCKED: 0, WAITING: 3, TIMED_WAITING: 4, TERMINATED: 0
ID   NAME                           GROUP           PRIORITY   STATE     %CPU      DELTA_TIME TIME      INTERRUPTE DAEMON
3    Finalizer                      system          8          WAITING   0.0       0.000      0:0.000   false      true
20   arthas-UserStat                system          9          WAITING   0.0       0.000      0:0.001   false      true
14   arthas-timer                   system          9          WAITING   0.0       0.000      0:0.000   false      true

3.2 trace 方法内部调用路径，并输出方法路径上的每个节点上耗时。

参数名称	参数说明
class-pattern	类名表达式匹配
method-pattern	方法名表达式匹配
condition-express	条件表达式
[E]	开启正则表达式匹配，默认为通配符匹配
[n:]	命令执行次数
#cost	方法执行耗时

trace demo.MathGame run -n 10 #获取前10条的调用路径
trace demo.MathGame run '#cost > 10' #获取耗时大于10ms的调用路径

[arthas@6]$ trace cn.my.mind.automation.engine.queue.KafkaConsumerService doPoll #trace 类名 方法名
Press Q or Ctrl+C to abort.
Affect(class count: 1 , method count: 1) cost in 272 ms, listenerId: 1
`---ts=2023-11-10 17:33:24;thread_name=automation-notification-consumer-3-thread-1;id=45;is_daemon=false;priority=5;TCCL=org.springframework.boot.loader.LaunchedURLClassLoader@49c2faae
    `---[26.55009ms] cn.my.mind.automation.engine.queue.KafkaConsumerService:doPoll()
        +---[1.04% 0.274931ms ] org.slf4j.Logger:trace() #43
        +---[95.90% 25.460767ms ] org.apache.kafka.clients.consumer.KafkaConsumer:poll() #45
        `---[0.11% 0.028355ms ] org.apache.kafka.clients.consumer.ConsumerRecords:isEmpty() #50

`---ts=2023-11-10 17:33:24;thread_name=automation-notification-consumer-3-thread-1;id=45;is_daemon=false;priority=5;TCCL=org.springframework.boot.loader.LaunchedURLClassLoader@49c2faae
    `---[26.932133ms] cn.kaiwu.mind.automation.engine.queue.KafkaConsumerService:doPoll()
        +---[0.11% 0.030745ms ] org.slf4j.Logger:trace() #43
        +---[95.49% 25.716754ms ] org.apache.kafka.clients.consumer.KafkaConsumer:poll() #45
        `---[0.05% 0.01474ms ] org.apache.kafka.clients.consumer.ConsumerRecords:isEmpty() #50

`---ts=2023-11-10 17:33:24;thread_name=automation-notification-consumer-3-thread-1;id=45;is_daemon=false;priority=5;TCCL=org.springframework.boot.loader.LaunchedURLClassLoader@49c2faae
    `---[25.267041ms] cn.my.mind.automation.engine.queue.KafkaConsumerService:doPoll()
        +---[0.66% 0.166906ms ] org.slf4j.Logger:trace() #43

3.3 反编译指定已加载类的源码

参数名称	参数说明
class-pattern	类名表达式匹配
[c:]	类所属 ClassLoader 的 hashcode
[classLoaderClass:]	指定执行表达式的 ClassLoader 的 class name
[E]	开启正则表达式匹配，默认为通配符匹配

$ jad --source-only demo.MathGame #反编译时只显示源代码
package demo;

import java.io.PrintStream;
import java.util.ArrayList;
import java.util.Iterator;
import java.util.List;
import java.util.Random;
import java.util.concurrent.TimeUnit;

public class MathGame {
    private static Random random = new Random();
    public int illegalArgumentCount = 0;
...

jad demo.MathGame main #反编译指定的函数

3.4 watch 观察到指定函数的调用情况。能观察到的范围为：返回值、抛出异常、入参，通过编写 OGNL 表达式进行对应变量的查看。
在这里插入图片描述

watch demo.MathGame primeFactors '{params, returnObj}' '#cost>200' -x 2 #表示只有当耗时大于 200ms 时才会输出，过滤掉执行时间小于 200ms 的调用

jvm 相关
dashboard - 当前系统的实时数据面板
getstatic - 查看类的静态属性
heapdump - dump java heap, 类似 jmap 命令的 heap dump 功能
jvm - 查看当前 JVM 的信息
logger - 查看和修改 logger
mbean - 查看 Mbean 的信息
memory - 查看 JVM 的内存信息
ognl - 执行 ognl 表达式
perfcounter - 查看当前 JVM 的 Perf Counter 信息
sysenv - 查看 JVM 的环境变量
sysprop - 查看和修改 JVM 的系统属性
thread - 查看当前 JVM 的线程堆栈信息
vmoption - 查看和修改 JVM 里诊断相关的 option
vmtool - 从 jvm 里查询对象，执行 forceGc

class/classloader 相关
classloader - 查看 classloader 的继承树，urls，类加载信息，使用 classloader 去 getResource
dump - dump 已加载类的 byte code 到特定目录
jad - 反编译指定已加载类的源码
mc - 内存编译器，内存编译.java文件为.class文件
redefine - 加载外部的.class文件，redefine 到 JVM 里
retransform - 加载外部的.class文件，retransform 到 JVM 里
sc - 查看 JVM 已加载的类信息
sm - 查看已加载类的方法信息

monitor/watch/trace 相关
monitor - 方法执行监控
stack - 输出当前方法被调用的调用路径
trace - 方法内部调用路径，并输出方法路径上的每个节点上耗时
tt - 方法执行数据的时空隧道，记录下指定方法每次调用的入参和返回信息，并能对这些不同的时间下调用进行观测
watch - 方法执行数据观测