要做网站找谁帮忙做,wordpress的主题修改,德州市网站建设,佛山高端网站设计转载自 Arthas使用
Authas — 开源的java诊断工具
下载安装
authas是一个jar包#xff0c;可以直接下载后运行
wget https://alibaba.github.io/arthas/arthas-boot.jarjava -jar arthas-boot.jar就可以启动起来。启动后#xff0c;authas会自动检测存在的java进程…转载自 Arthas使用
Authas — 开源的java诊断工具
下载安装
authas是一个jar包可以直接下载后运行
wget https://alibaba.github.io/arthas/arthas-boot.jarjava -jar arthas-boot.jar就可以启动起来。启动后authas会自动检测存在的java进程这时候需要选择你想要诊断的进程回车即可。如下图所示 如果不知道某个java进程的详情可以使用
jps -mlVv
或
ps -ef| grep java来查看. 常用命令
基础命令
help——查看命令帮助信息
cat——打印文件内容和linux里的cat命令类似
pwd——返回当前的工作目录和linux命令类似
cls——清空当前屏幕区域
session——查看当前会话的信息
reset——重置增强类将被 Arthas 增强过的类全部还原Arthas 服务端关闭时会重置所有增强过的类
version——输出当前目标 Java 进程所加载的 Arthas 版本号
history——打印命令历史
quit——退出当前 Arthas 客户端其他 Arthas 客户端不受影响
shutdown——关闭 Arthas 服务端所有 Arthas 客户端全部退出
keymap——Arthas快捷键列表及自定义快捷键JVM相关
Dashboard
dashboardUSAGE:dashboard [-b] [-i value] [-n value]SUMMARY:Overview of target jvms thread, memory, gc, vm, tomcat info.EXAMPLES:dashboarddashboard -n 10dashboard -i 2000WIKI:https://alibaba.github.io/arthas/dashboardOPTIONS:-b, --batch Execute this command in batch mode.-i, --interval value The interval (in ms) between two executions, default is 5000 ms.-n, --number-of-execution value The number of times this command will be executed.可以看到这里会显示出线程(按照cpu占用百分比倒排)、内存(堆空间实时情况)、GC情况等数据。
常用的参数
-i 400ms 每次执行间隔时间
-n 执行多少次dashboard不指定的话会一直刷新
thread
thread命令用来查看当前jvm中的线程信息的如下图所示 可以看到默认执行thread命令会直接打印出当前所有的线程。
$ threadUSAGE:thread [-b] [-i value] [-n value] [id]SUMMARY:Display thread info, thread stackEXAMPLES:threadthread 51thread -n -1thread -n 5thread -bthread -i 2000WIKI:https://alibaba.github.io/arthas/threadOPTIONS:-b, --include-blocking-th Find the thread who is holding a lock that blocks tread he most number of threads.-i, --sample-interval va Specify the sampling interval (in ms) when calculatlue ing cpu usage.-n, --top-n-threads valu The number of thread(s) to show, ordered by cpu utie lization, -1 to show all.id Show thread stack常用参数
-b 查看目前block的线程
-i 5000ms 查看在接下来的多长时间内 统计cpu利用率
-n 5 查看cpu占用率前5的线程的堆栈信息
thread_id 直接跟着线程id可以看到thread的堆栈信息
如下
$ thread -n 1
as-command-execute-daemon Id396 cpuUsage53% RUNNABLEat sun.management.ThreadImpl.dumpThreads0(Native Method)at sun.management.ThreadImpl.getThreadInfo(ThreadImpl.java:448)at com.taobao.arthas.core.command.monitor200.ThreadCommand.processTopBusyThreads(ThreadCommand.java:133)at com.taobao.arthas.core.command.monitor200.ThreadCommand.process(ThreadCommand.java:79)at com.taobao.arthas.core.shell.command.impl.AnnotatedCommandImpl.process(AnnotatedCommandImpl.java:82)at com.taobao.arthas.core.shell.command.impl.AnnotatedCommandImpl.access$100(AnnotatedCommandImpl.java:18)at com.taobao.arthas.core.shell.command.impl.AnnotatedCommandImpl$ProcessHandler.handle(AnnotatedCommandImpl.java:111)at com.taobao.arthas.core.shell.command.impl.AnnotatedCommandImpl$ProcessHandler.handle(AnnotatedCommandImpl.java:108)at com.taobao.arthas.core.shell.system.impl.ProcessImpl$CommandProcessTask.run(ProcessImpl.java:370)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 1- java.util.concurrent.ThreadPoolExecutor$Worker21b51d37Affect(row-cnt:0) cost in 541 ms.可以看到这里占用cpu最高的一个线程的栈信息。
jvm
查看当前jvm信息
sysprop
查看和修改JVM的系统属性
sysenv
查看JVM的环境变量
getstatic
查看类的静态属性
class/classloader相关
mc
Memory Compiler/内存编译器编译.java文件生成.class。
mc /tmp/Test.javaredefine
加载外部的.class文件redefine jvm已加载的类。
redefine /tmp/Test.class
redefine -c 327a647b /tmp/Test.class /tmp/Test\$Inner.classsc
Search class
查看jvm中已加载的类不过直接执行sc并没有太多信息如下
$ sc com.google.common.hash.HashFunction
com.google.common.hash.AbstractHashFunction
com.google.common.hash.HashFunction
com.google.common.hash.MessageDigestHashFunction
Affect(row-cnt:3) cost in 213 ms.
需要加入-d参数如下
$ sc -d com.google.common.hash.HashFunctionclass-info com.google.common.hash.AbstractHashFunctioncode-sourcename com.google.common.hash.AbstractHashFunctionisInterface falseisAnnotation falseisEnum falseisAnonymousClass falseisArray falseisLocalClass falseisMemberClass falseisPrimitive falseisSynthetic falsesimple-name AbstractHashFunctionmodifier abstractannotation com.google.errorprone.annotations.Immutableinterfaces com.google.common.hash.HashFunctionsuper-class -java.lang.Objectclass-loader -com.intellij.util.lang.UrlClassLoader277050dcclassLoaderHash 277050dcclass-info com.google.common.hash.HashFunctioncode-sourcename com.google.common.hash.HashFunctionisInterface trueisAnnotation falseisEnum falseisAnonymousClass falseisArray falseisLocalClass falseisMemberClass falseisPrimitive falseisSynthetic falsesimple-name HashFunctionmodifier abstract,interface,publicannotation com.google.errorprone.annotations.Immutableinterfacessuper-classclass-loader -com.intellij.util.lang.UrlClassLoader277050dcclassLoaderHash 277050dcclass-info com.google.common.hash.MessageDigestHashFunctioncode-sourcename com.google.common.hash.MessageDigestHashFunctionisInterface falseisAnnotation falseisEnum falseisAnonymousClass falseisArray falseisLocalClass falseisMemberClass falseisPrimitive falseisSynthetic falsesimple-name MessageDigestHashFunctionmodifier finalannotation com.google.errorprone.annotations.Immutableinterfaces java.io.Serializablesuper-class -com.google.common.hash.AbstractHashFunction-java.lang.Objectclass-loader -com.intellij.util.lang.UrlClassLoader277050dcclassLoaderHash 277050dcAffect(row-cnt:3) cost in 102 ms.
可以看到这里有更详细的信息。
具体的参数如下
$ help scUSAGE:sc [-d] [-x value] [-f] [-h] [-E] class-patternSUMMARY:Search all the classes loaded by JVMEXAMPLES:sc -d org.apache.commons.lang.StringUtilssc -d org/apache/commons/lang/StringUtilssc -d *StringUtilssc -d -f org.apache.commons.lang.StringUtilssc -E org\\.apache\\.commons\\.lang\\.StringUtilsWIKI:https://alibaba.github.io/arthas/scOPTIONS:-d, --details Display the details of class-x, --expand value Expand level of object (0 by default)-f, --field Display all the member variables-h, --help this help-E, --regex Enable regular expression to match (wildcard matching by default)class-pattern Class name pattern, use either . or / as separator
sm
search method
$ sm xx.xxx.xx.xx.xxx.xx.CouponService
xx.xxx.xx.xx.xxx.xx.CouponServiceImpl init()V
xx.xxx.xx.xx.xxx.xx.CouponServiceImpl matchCouponOnShopIds(Ljava/lang/Long;Ljava/util/List;)Ljava/util/Map;
xx.xxx.xx.xx.xxx.xx.CouponServiceImpl$$EnhancerByGuice$$9b64c3b8 init()V
xx.xxx.xx.xx.xxx.xx.CouponServiceImpl$$EnhancerByGuice$$9b64c3b8 CGLIB$matchCouponOnShopIds$0(Ljava/lang/Long;Ljava/util/List;)Ljava/util/Map;
xx.xxx.xx.xx.xxx.xx.CouponServiceImpl$$EnhancerByGuice$$9b64c3b8 CGLIB$SET_THREAD_CALLBACKS([Lcom/google/inject/internal/cglib/pr$ oxy/$Callback;)V
xx.xxx.xx.xx.xxx.xx.CouponServiceImpl$$EnhancerByGuice$$9b64c3b8 CGLIB$SET_STATIC_CALLBACKS([Lcom/google/inject/internal/cglib/proxy/$Callback;)V
xx.xxx.xx.xx.xxx.xx.CouponServiceImpl$$EnhancerByGuice$$9b64c3b8 CGLIB$STATICHOOK35()V
xx.xxx.xx.xx.xxx.xx.CouponServiceImpl$$EnhancerByGuice$$9b64c3b8 CGLIB$BIND_CALLBACKS(Ljava/lang/Object;)V
xx.xxx.xx.xx.xxx.xx.CouponServiceImpl$$EnhancerByGuice$$9b64c3b8 CGLIB$findMethodProxy(Lcom/google/inject/internal/cglib/core/$Signature;)Lcom/google/inject/internal/cglib/proxy/$MethodProxy;
xx.xxx.xx.xx.xxx.xx.CouponServiceImpl$$EnhancerByGuice$$9b64c3b8 matchCouponOnShopIds(Ljava/lang/Long;Ljava/util/List;)Ljava/util/Map;
xx.xxx.xx.xx.xxx.xx.CouponService matchCouponOnShopIds(Ljava/lang/Long;Ljava/util/List;)Ljava/util/Map;
com.sun.proxy.$Proxy197 init(Ljava/lang/reflect/InvocationHandler;)V
com.sun.proxy.$Proxy197 matchCouponOnShopIds(Ljava/lang/Long;Ljava/util/List;)Ljava/util/Map;
com.sun.proxy.$Proxy197 equals(Ljava/lang/Object;)Z
com.sun.proxy.$Proxy197 toString()Ljava/lang/String;
com.sun.proxy.$Proxy197 hashCode()I
Affect(row-cnt:16) cost in 53 ms.
可以看到通过sm命令可以查到对应类的所有方法
通过加入-d参数可以获取method的信息信息
$ sm xx.xxx.xx.xx.xxx.xx.CouponService -ddeclaring-class xx.xxx.xx.xx.xxx.xx.CouponServiceImplconstructor-name initmodifier publicannotationparametersexceptionsdeclaring-class xx.xxx.xx.xx.xxx.xx.CouponServiceImplmethod-name matchCouponOnShopIdsmodifier publicannotationparameters java.lang.Longjava.util.Listreturn java.util.Mapexceptions xx.xxx.xx.xx.xxx.xx.ServiceException...
sh
jad
反编译代码如下
$ jad com.google.common.hash.HashFunctionClassLoader:
-com.intellij.util.lang.UrlClassLoader277050dcLocation:/** Decompiled with CFR 0_132.** Could not load the following classes:* com.google.common.hash.Funnel* com.google.common.hash.HashCode* com.google.common.hash.Hasher* com.google.errorprone.annotations.Immutable*/
package com.google.common.hash;import com.google.common.hash.Funnel;
import com.google.common.hash.HashCode;
import com.google.common.hash.Hasher;
import com.google.errorprone.annotations.Immutable;
import java.nio.ByteBuffer;
import java.nio.charset.Charset;Immutable
public interface HashFunction {public int bits();public HashCode hashLong(long var1);public HashCode hashInt(int var1);public T HashCode hashObject(T var1, Funnel? super T var2);public HashCode hashUnencodedChars(CharSequence var1);public HashCode hashString(CharSequence var1, Charset var2);public HashCode hashBytes(ByteBuffer var1);public HashCode hashBytes(byte[] var1, int var2, int var3);public HashCode hashBytes(byte[] var1);public Hasher newHasher();public Hasher newHasher(int var1);
}Affect(row-cnt:1) cost in 1730 ms.
classloader
查看classloader的继承树urls类加载信息 查看其参数如下
$ help classloaderUSAGE:classloader [-a] [-c value] [-h] [-i] [-l] [--load value] [-r value] [-t]SUMMARY:Show classloader infoEXAMPLES:classloaderclassloader -tclassloader -lclassloader -c 327a647bclassloader -c 327a647b -r META-INF/MANIFEST.MFclassloader -aclassloader -a -c 327a647bclassloader -c 659e0bfd --load demo.MathGameWIKI:https://alibaba.github.io/arthas/classloaderOPTIONS:-a, --all Display all classes loaded by ClassLoader-c, --classloader value The hash code of the special ClassLoader-h, --help this help-i, --include-reflection-classloader Include sun.reflect.DelegatingClassLoader-l, --list-classloader Display statistics info by classloader instance--load value Use ClassLoader to load class, wont work without -c specified-r, --resource value Use ClassLoader to find resources, wont work without -c specified-t, --tree Display ClassLoader tree
-t : 把classloader的树打印出来也会打印出来粗略的hashcode
-l : 根据classloader实例的个数来打印classloader会打印出来hashcode
-c : 用classloader对应的hashcode 来查看对应的jar urls
查看classloader tree信息
$ classloader -t
-BootstrapClassLoader
-sun.misc.Launcher$ExtClassLoader6379eb-com.taobao.arthas.agent.ArthasClassloader5ab570f5-sun.misc.Launcher$AppClassLoader18b4aac2-com.alibaba.fastjson.util.ASMClassLoader34c3f8d2-com.alibaba.fastjson.util.ASMClassLoader463b35ac-com.alibaba.jvm.sandbox.agent.SandboxClassLoader567d299b-ModuleClassLoader[crc323750510264;file/data/.ewatch/ewatch-install/sandbox/lib/../module/sandbox-mgr-module.jar;]-ModuleClassLoader[crc322030492332;file/data/.ewatch/ewatch-install/sandbox/ewatch/ewatch-agent-jar-with-dependencies.jar;]-com.alibaba.jvm.sandbox.core.classloader.ProviderClassLoader42f93a98
Affect(row-cnt:10) cost in 20 ms.
查看URLClassLoader实际的urls
$ classloader -c 5ab570f5
file:/root/.arthas/lib/3.1.1/arthas/arthas-core.jarAffect(row-cnt:1790) cost in 19 ms.
使用ClassLoader去查找resource
$ classloader -c 3d4eac69 -r META-INF/MANIFEST.MFjar:file:/System/Library/Java/Extensions/MRJToolkit.jar!/META-INF/MANIFEST.MFjar:file:/private/tmp/arthas-demo.jar!/META-INF/MANIFEST.MFjar:file:/Users/hengyunabc/.arthas/lib/3.0.5/arthas/arthas-agent.jar!/META-INF/MANIFEST.MF
也可以尝试查找类的class文件
$ classloader -c 1b6d3586 -r java/lang/String.classjar:file:/Library/Java/JavaVirtualMachines/jdk1.8.0_60.jdk/Contents/Home/jre/lib/rt.jar!/java/lang/String.class
使用ClassLoader去加载类
$ classloader -c 3d4eac69 --load demo.MathGame
load class success.class-info demo.MathGamecode-source /private/tmp/arthas-demo.jarname demo.MathGameisInterface falseisAnnotation falseisEnum falseisAnonymousClass falseisArray falseisLocalClass falseisMemberClass falseisPrimitive falseisSynthetic falsesimple-name MathGamemodifier publicannotationinterfacessuper-class -java.lang.Objectclass-loader -sun.misc.Launcher$AppClassLoader3d4eac69-sun.misc.Launcher$ExtClassLoader66350f69classLoaderHash 3d4eac69
monitor/watch/trace相关
monitor
对匹配 class-patternmethod-pattern的类、方法的调用进行监控。
monitor 命令是一个非实时返回命令.
实时返回命令是输入之后立即返回而非实时返回的命令则是不断的等待目标 Java 进程返回信息直到用户输入 CtrlC 为止。
服务端是以任务的形式在后台跑任务植入的代码随着任务的中止而不会被执行所以任务关闭后不会对原有性能产生太大影响而且原则上任何Arthas命令不会引起原有业务逻辑的改变。
监控的维度说明
监控项说明timestamp时间戳classJava类method方法构造方法、普通方法total调用次数success成功次数fail失败次数rt平均RTfail-rate失败率$ monitor -c 5 demo.MathGame primeFactors
Press CtrlC to abort.
Affect(class-cnt:1 , method-cnt:1) cost in 94 ms.timestamp class method total success fail avg-rt(ms) fail-rate
-----------------------------------------------------------------------------------------------2018-12-03 19:06:38 demo.MathGame primeFactors 5 1 4 1.15 80.00%timestamp class method total success fail avg-rt(ms) fail-rate
-----------------------------------------------------------------------------------------------2018-12-03 19:06:43 demo.MathGame primeFactors 5 3 2 42.29 40.00%timestamp class method total success fail avg-rt(ms) fail-rate
-----------------------------------------------------------------------------------------------2018-12-03 19:06:48 demo.MathGame primeFactors 5 3 2 67.92 40.00%timestamp class method total success fail avg-rt(ms) fail-rate
-----------------------------------------------------------------------------------------------2018-12-03 19:06:53 demo.MathGame primeFactors 5 2 3 0.25 60.00%timestamp class method total success fail avg-rt(ms) fail-rate
-----------------------------------------------------------------------------------------------2018-12-03 19:06:58 demo.MathGame primeFactors 1 1 0 0.45 0.00%timestamp class method total success fail avg-rt(ms) fail-rate
-----------------------------------------------------------------------------------------------2018-12-03 19:07:03 demo.MathGame primeFactors 2 2 0 3182.72 0.00%
watch
查看函数的参数、返回值、异常信息如果有请求触发就回打印对应的数据。用法如下
$ help watchUSAGE:watch [-b] [-e] [-x value] [-f] [-h] [-n value] [-E] [-M value] [-s] class-pattern method-pattern express [condition-express]SUMMARY:Display the input/output parameter, return object, and thrown exception of specified method invocationThe express may be one of the following expression (evaluated dynamically):target : the objectclazz : the objects classmethod : the constructor or methodparams : the parameters array of methodparams[0..n] : the element of parameters arrayreturnObj : the returned object of methodthrowExp : the throw exception of methodisReturn : the method ended by returnisThrow : the method ended by throwing exception#cost : the execution time in ms of method invocationExamples:watch -b org.apache.commons.lang.StringUtils isBlank paramswatch -f org.apache.commons.lang.StringUtils isBlank returnObjwatch org.apache.commons.lang.StringUtils isBlank {params, target, returnObj} -x 2watch -bf *StringUtils isBlank paramswatch *StringUtils isBlank params[0]watch *StringUtils isBlank params[0] params[0].length1watch *StringUtils isBlank params #cost100watch -E -b org\.apache\.commons\.lang\.StringUtils isBlank params[0]OPTIONS:-b, --before Watch before invocation-e, --exception Watch after throw exception-x, --expand value Expand level of object (1 by default)-f, --finish Watch after invocation, enable by default-h, --help this help-n, --limits value Threshold of execution times-E, --regex Enable regular expression to match (wildcard matching by default)-M, --sizeLimit value Upper size limit in bytes for the result (10 * 1024 * 1024 by default)-s, --success Watch after successful invocationclass-pattern The full qualified class name you want to watchmethod-pattern The method name you want to watchexpress the content you want to watch, written by ognl.Examples:paramsparams[0]params[0]params[1]{params[0], target, returnObj}returnObjthrowExptargetclazzmethodcondition-express Conditional expression in ognl style, for example:TRUE : 11TRUE : trueFALSE : falseTRUE : params.length0FALSE : 12
具体执行如下查看请求参数
$ watch xxx.xxx.xxx.xxx.BerlinService view paramsPress Q or CtrlC to abort.
Affect(class-cnt:3 , method-cnt:3) cost in 393 ms.
ts2019-05-17 14:43:36; [cost37.391098ms] resultObject[][BerlinRequest[BerlinRequest(activityIdRC_12, userId309314913, latitude31.224360913038254, longitude121.55064392834902, userAgentRajax/1 MI_MAX/helium Android/7.0 Display/NRD90M me/8.16.1 Channel/tengxun ID/e75ef7ec-4069-38ee-83c4-51fb7067f3e1; KERNEL_VERSION:3.10.84-perf-gd39c060 API_Level:24 Hardware: Mozilla/5.0 (Linux; Android 7.0; MI MAX Build/NRD90M; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/64.0.3282.137 Mobile Safari/537.36 AliApp(ELMC/8.16.1) TTID/offical WindVane/8.5.0,UT4Aplus/0.2.16, deviceIdnull, limit8, offset0)],
]
ts2019-05-17 14:43:36; [cost46.358372ms] resultObject[][BerlinRequest[BerlinRequest(activityIdRC_12, userId309314913, latitude31.224360913038254, longitude121.55064392834902, userAgentRajax/1 MI_MAX/helium Android/7.0 Display/NRD90M me/8.16.1 Channel/tengxun ID/e75ef7ec-4069-38ee-83c4-51fb7067f3e1; KERNEL_VERSION:3.10.84-perf-gd39c060 API_Level:24 Hardware: Mozilla/5.0 (Linux; Android 7.0; MI MAX Build/NRD90M; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/64.0.3282.137 Mobile Safari/537.36 AliApp(ELMC/8.16.1) TTID/offical WindVane/8.5.0,UT4Aplus/0.2.16, deviceIdnull, limit8, offset0)],
]
查看请求参数及相应
$ watch xx.xxx.xx.xx.xxx.xx.BerlinService view {params[0], returnObj}Press Q or CtrlC to abort.
Affect(class-cnt:3 , method-cnt:3) cost in 416 ms.
ts2019-05-17 14:52:38; [cost33.968815ms] resultArrayList[BerlinRequest[BerlinRequest(activityIdRC_12, userId117043063, latitude22.682775497436523, longitude114.04010772705078, userAgentRajax/1 Apple/iPhone9,2 iOS/12.2 me/8.16.3 ID/6E92FDB8-FF9F-4F11-BC1A-490E67B39047; IsJailbroken/0 ASI/30F47DE7-0DE5-488E-A479-C94EB9284301 Mozilla/5.0 (iPhone; CPUiPhone OS 12_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 AliApp(ELMC/8.16.3) UT4Aplus/0.0.4 WindVane/8.5.0 1242x2208 WK, deviceIdnull, limit8, offset0)],BerlinCommonResponse[xx.xxx.xx.xx.xxx.xx.BerlinCommonResponse4c5df030],
]
可以看到包含了请求及相应数据、整体cost-time,不过需要注意的是这里并没有打印出所有详细数据。如果需要打印所有数据加入-x参数表示遍历深度可以调整来打印具体的参数和结果内容。默认-x 的深度为1可以指定 2 3 等,如下
$ watch xx.xxx.xx.xx.xxx.xx.BerlinService view {params, returnObj} -x 2
ts2019-05-20 11:12:57; [cost41.677987ms] resultArrayList[Object[][BerlinRequest[BerlinRequest(activityIdRC_12, userId840800602, latitude36.042474, longitude103.849928, userAgentMozilla/5.0 (Linux; U; Android 8.1.0; zh-CN; DUB-AL00 Build/HUAWEIDUB-AL00) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/57.0.2987.108 UCBrowser/11.8.8.968 UWS/2.13.2.74 Mobile Safari/537.36 UCBS/2.13.2.74_190419112332 NebulaSDK/1.8.100112 Nebula AlipayDefined(nt:4G,ws:360|0|2.0) AliApp(AP/10.1.62.5549) AlipayClient/10.1.62.5549 Language/zh-Hans useStatusBar/true isConcaveScreen/true, deviceIdnull, limit8, offset0)],],BerlinCommonResponse[codeString[200],messageString[成功],dataArrayList[isEmptyfalse;size7],],
]
如过需要按照耗时进行过滤需要加入 #cost200 代表耗时超过200ms的才会打印出来。
watch/stack/trace这个三个命令都支持#cost
trace
方法内部调用路径并输出方法路径上的每个节点上耗时
查看help
$ help traceUSAGE:trace [-h] [-j] [-n value] [-p value] [-E] class-pattern method-pattern [condition-express]SUMMARY:Trace the execution time of specified method invocation.FALSE : falseTRUE : params.length0FALSE : 12The express may be one of the following expression (evaluated dynamically):target : the objectclazz : the objects classmethod : the constructor or methodparams : the parameters array of methodparams[0..n] : the element of parameters arrayreturnObj : the returned object of methodthrowExp : the throw exception of methodisReturn : the method ended by returnisThrow : the method ended by throwing exception#cost : the execution time in ms of method invocationEXAMPLES:trace org.apache.commons.lang.StringUtils isBlanktrace *StringUtils isBlanktrace *StringUtils isBlank params[0].length1trace *StringUtils isBlank #cost100trace -E org\\.apache\\.commons\\.lang\\.StringUtils isBlanktrace -E com.test.ClassA|org.test.ClassB method1|method2|method3WIKI:https://alibaba.github.io/arthas/traceOPTIONS:-h, --help this help-j, --jdkMethodSkip skip jdk method trace-n, --limits value Threshold of execution times-p, --path value path tracing pattern-E, --regex Enable regular expression to match (wildcard matching by default)class-pattern Class name pattern, use either . or / as separatormethod-pattern Method name patterncondition-express Conditional expression in ognl style, for example:TRUE : 11TRUE : true
平时主要使用-j (忽略jdk method trace)、#cost10(过滤耗时时间) -n (执行次数)
$ trace xx.xxx.xx.xx.xxx.xx.QueryAction queryByReq -j #cost5 -n 5Press Q or CtrlC to abort.
Affect(class-cnt:1 , method-cnt:1) cost in 781 ms.
---ts2019-05-20 15:27:10;thread_namethread-272;id1fb;is_daemonfalse;priority5;TCCLsun.misc.Launcher$AppClassLoader18b4aac2---[12.250248ms] xx.xxx.xx.xx.xxx.xx.QueryAction:queryByReq()......stack
输出当前方法被调用的调用路径
很多时候我们都知道一个方法被执行但这个方法被执行的路径非常多或者你根本就不知道这个方法是从那里被执行了此时你需要的是 stack 命令。
主要使用-n 命令用于控制执行次数
stack xx.xxx.xx.xx.xxx.xx.QueryAction queryByReq -n 1
tt
先看个例子
$ tt -t xx.xxx.xx.xx.xxx.xx.QueryAction queryByReq -n 5
Press Q or CtrlC to abort.
Affect(class-cnt:1 , method-cnt:1) cost in 995 ms.IND TIMESTAMP COST( IS- IS OBJECT CLASS METHODEX ms) RET -EXP
--------------------------------------------------------------------------------100 2019-05-20 17.52 tru fa 0x7df629 QueryValidSkuAct queryValidSkuActi5 15:41:29 694 e ls 87 ivityAction vityByReqe100 2019-05-20 10.26 tru fa 0x4bb52c QueryValidSkuAct queryValidSkuActi6 15:41:29 7017 e ls 96 ivityAction vityByReqe100 2019-05-20 19.80 tru fa 0x20d351 QueryValidSkuAct queryValidSkuActi7 15:41:29 7279 e ls d3 ivityAction vityByReqe100 2019-05-20 19.84 tru fa 0x286366 QueryValidSkuAct queryValidSkuActi8 15:41:29 4526 e ls b4 ivityAction vityByReqe100 2019-05-20 19.91 tru fa 0x31297b QueryValidSkuAct queryValidSkuActi9 15:41:29 0582 e ls 01 ivityAction vityByReqe
Command execution times exceed limit: 5, so command will exit. You can set it with -n option.101 2019-05-20 19.94 tru fa 0x7a3c2f QueryValidSkuAct queryValidSkuActi0 15:41:29 8529 e ls c4 ivityAction vityByReq
表格字段说明
表格字段字段解释INDEX时间片段记录编号每一个编号代表着一次调用后续tt还有很多命令都是基于此编号指定记录操作非常重要。TIMESTAMP方法执行的本机时间记录了这个时间片段所发生的本机时间COST(ms)方法执行的耗时IS-RET方法是否以正常返回的形式结束IS-EXP方法是否以抛异常的形式结束OBJECT执行对象的hashCode()注意曾经有人误认为是对象在JVM中的内存地址但很遗憾他不是。但他能帮助你简单的标记当前执行方法的类实体CLASS执行的类名METHOD执行的方法名
-t
tt 命令有很多个主参数-t 就是其中之一。这个参数的表明希望记录下类 *Test 的 print 方法的每次执行情况。
-n 3
当你执行一个调用量不高的方法时可能你还能有足够的时间用 CTRLC 中断 tt 命令记录的过程但如果遇到调用量非常大的方法瞬间就能将你的 JVM 内存撑爆。
此时你可以通过 -n 参数指定你需要记录的次数当达到记录次数时 Arthas 会主动中断tt命令的记录过程避免人工操作无法停止的情况。
检索调用记录
当你用 tt 记录了一大片的时间片段之后你希望能从中筛选出自己需要的时间片段这个时候你就需要对现有记录进行检索。
假设我们有这些记录
$ tt -lINDEX TIMESTAMP COST(ms) IS-RET IS-EXP OBJECT CLASS METHOD
-------------------------------------------------------------------------------------------------------------------------------------1000 2018-12-04 11:15:38 1.096236 false true 0x4b67cf4d MathGame primeFactors1001 2018-12-04 11:15:39 0.191848 false true 0x4b67cf4d MathGame primeFactors1002 2018-12-04 11:15:40 0.069523 false true 0x4b67cf4d MathGame primeFactors1003 2018-12-04 11:15:41 0.186073 false true 0x4b67cf4d MathGame primeFactors1004 2018-12-04 11:15:42 17.76437 true false 0x4b67cf4d MathGame primeFactors91005 2018-12-04 11:15:43 0.4776 false true 0x4b67cf4d MathGame primeFactors
Affect(row-cnt:6) cost in 4 ms.
我需要筛选出 primeFactors 方法的调用信息
$ tt -s method.nameprimeFactorsINDEX TIMESTAMP COST(ms) IS-RET IS-EXP OBJECT CLASS METHOD
-------------------------------------------------------------------------------------------------------------------------------------1000 2018-12-04 11:15:38 1.096236 false true 0x4b67cf4d MathGame primeFactors1001 2018-12-04 11:15:39 0.191848 false true 0x4b67cf4d MathGame primeFactors1002 2018-12-04 11:15:40 0.069523 false true 0x4b67cf4d MathGame primeFactors1003 2018-12-04 11:15:41 0.186073 false true 0x4b67cf4d MathGame primeFactors1004 2018-12-04 11:15:42 17.76437 true false 0x4b67cf4d MathGame primeFactors91005 2018-12-04 11:15:43 0.4776 false true 0x4b67cf4d MathGame primeFactors
Affect(row-cnt:6) cost in 607 ms.
你需要一个 -s 参数。同样的搜索表达式的核心对象依旧是 Advice 对象。
查看调用信息
对于具体一个时间片的信息而言你可以通过 -i 参数后边跟着对应的 INDEX 编号查看到他的详细信息。
$ tt -i 1003INDEX 1003GMT-CREATE 2018-12-04 11:15:41COST(ms) 0.186073OBJECT 0x4b67cf4dCLASS demo.MathGameMETHOD primeFactorsIS-RETURN falseIS-EXCEPTION truePARAMETERS[0] Integer[-564322413]THROW-EXCEPTION java.lang.IllegalArgumentException: number is: -564322413, need 2at demo.MathGame.primeFactors(MathGame.java:46)at demo.MathGame.run(MathGame.java:24)at demo.MathGame.main(MathGame.java:16)Affect(row-cnt:1) cost in 11 ms.
重做一次调用
当你稍稍做了一些调整之后你可能需要前端系统重新触发一次你的调用此时得求爷爷告奶奶的需要前端配合联调的同学再次发起一次调用。而有些场景下这个调用不是这么好触发的。
tt 命令由于保存了当时调用的所有现场信息所以我们可以自己主动对一个 INDEX 编号的时间片自主发起一次调用从而解放你的沟通成本。此时你需要 -p 参数。通过 --replay-times 指定 调用次数通过 --replay-interval 指定多次调用间隔(单位ms, 默认1000ms)
$ tt -i 1004 -pRE-INDEX 1004GMT-REPLAY 2018-12-04 11:26:00OBJECT 0x4b67cf4dCLASS demo.MathGameMETHOD primeFactorsPARAMETERS[0] Integer[946738738]IS-RETURN trueIS-EXCEPTION falseCOST(ms) 0.186073RETURN-OBJ ArrayList[Integer[2],Integer[11],Integer[17],Integer[2531387],]
Time fragment[1004] successfully replayed.
Affect(row-cnt:1) cost in 14 ms.
你会发现结果虽然一样但调用的路径发生了变化有原来的程序发起变成了 Arthas 自己的内部线程发起的调用了。 需要强调的点 ThreadLocal 信息丢失 很多框架偷偷的将一些环境变量信息塞到了发起调用线程的 ThreadLocal 中由于调用线程发生了变化这些 ThreadLocal 线程信息无法通过 Arthas 保存所以这些信息将会丢失。 一些常见的 CASE 比如鹰眼的 TraceId 等。 引用的对象 需要强调的是tt 命令是将当前环境的对象引用保存起来但仅仅也只能保存一个引用而已。如果方法内部对入参进行了变更或者返回的对象经过了后续的处理那么在 tt 查看的时候将无法看到当时最准确的值。这也是为什么 watch 命令存在的意义。
