一些JDK自带的性能分析利器

有时候碰到服务器CPU飙升或者程序卡死之类的问题,一般都不太好定位。这类bug一般都隐藏的比较深并且还可能是偶发性的,比较棘手。

对于此类问题,一般我们都有固定的分析流程。借助于JDK自带的一些分析工具,比如jstack、jmap、jstat一类的命令行工具,除此之外,还有jconsole、mat、jvisualvm这些图形界面分析工具。

这篇文章基于JDK8,操作系统是macOS 12.0.1

1、一些命令行分析工具

这些命令行分析工具都在jdk/bin目录下

image-20220316160402785-7417844.png

解压jdk/lib/tool.jar可以得到上述工具的class文件

image-20220316160755917-7418077.png

1.1 jps - JVM Process Status Tool

作用:列出正在运行的虚拟机进程,并显示虚拟机执行主类名称以及这些进程的本地虚拟机唯一ID。

image-20220316193716333-7430637.png

第一个参数说明:

  • -q:默认携带的参数,显示进程ID。

  • -m:显示进程ID,主类名称,以及传入main方法的参数。

  • -l:显示进程ID,主类全名。

  • -v:显示进程ID,主类名称,以及传入JVM的参数。

  • -V:显示进程ID,主类名称。

[-mlvV]可以任意组合使用。

第二个参数说明:

  • hostid:服务器的ip地址。不指定的情况下,默认为当前服务器。如果要查看其他机器上的JVM进程,需要在待查看机器上启动jstatd。

1.2 jstat - JVM Statistics Monitoring Tool

作用:监视虚拟机各种运行状态信息,可以显示本地或者远程虚拟机进程中的类装载、内存、垃圾收集、JIT编译等运行数据。

命令格式:jstat -<option> [-t] [-h<lines>] <vmid> [<interval> [<count>]]

参数说明

第一个参数:option,代表用户希望查询的虚拟机信息,主要分为3类:类装载、垃圾收集和运行期编译情况。具体选项如下:

  • -class:显示有关类加载器行为的统计信息。

  • -compiler:显示有关java hotspot vm即时编译器行为的统计信息。

  • -gc:显示有关垃圾收集堆行为的统计信息。

  • -gccapacity:显示有关各个垃圾回收代容量及其相应容量的统计信息。

  • -gccause:显示有关垃圾收集统计信息(同-gcutil),以及上一次和当前垃圾收集事件的原因。

  • -gcnew:显示新生代行为的统计信息。

  • -gcnewcapacity:显示有关新生代大小及其相应空间的统计信息。

  • -gcold:显示老年代行为的统计信息和元空间统计信息。

  • -gcoldcapacity:显示有关老年代大小的统计信息。

  • -gcmetacapacity:显示有关元空间大小的统计信息。

  • -gcutil:显示有关垃圾收集统计信息。

  • -printcompilation:显示java hotspot vm编译方法统计信息。

第二个参数:vmid。如果是本地虚拟机进程,那么vmid和本地虚拟机唯一ID是一致的。如果是远程虚拟机进程,那么vmid的格式是:protocol:lvmid[@hostname[:port]/servername]

第三个参数:interval。采样间隔,单位为s或ms,默认单位是ms,必须为整数。指定该参数,jstat命令将在每个间隔产生输出。

第四个参数:count。要显示的样本数。

import java.io.IOException;

/**
 * -class:
 *  Loaded:已加载的类数量。
 *  Bytes:已加载的kb数。
 *  Unloaded:卸载的类数量。
 *  Bytes:卸载的kb数。
 *  Time:执行类加载和卸载的耗时。
 *
 * -compiler:
 *  Compiled:执行的编译任务数。
 *  Failed:编译失败的任务数。
 *  Invalid:无效的编译任务数。
 *  Time:执行编译任务所花费的时间。
 *  FailedType:失败的编译类型。
 *  FailedMethod:失败的编译类名和方法。
 */
public class Demo1_jstat {
    public static void main(String[] args) throws IOException {
        System.out.println("jstat");
        System.in.read();
    }
}

启动上面的demo后

import java.io.IOException;

/**
 * -gc: 垃圾收集的堆统计信息
 *  S0C:s0区的容量(kb)
 *  S1C:s1区的容量(kb)
 *  S0U:s0区的使用大小(kb)
 *  S1U:s1区的使用大学(kb)
 *  EC:eden区的容量(kb)
 *  EU:eden区的使用大小(kb)
 *  OC:老年代容量(kb)
 *  OU:老年代的使用大小(kb)
 *  MC:元空间的容量(kb)
 *  MU:元空间的使用大小(kb)
 *  CCSC:压缩的类空间容量(kb)
 *  CCSU:使用的压缩类空间大小(kb)
 *  YGC:新生代垃圾收集次数
 *  YGCT:新生代垃圾回收时间
 *  FGC:full gc收集次数
 *  FGCT:full gc垃圾回收时间
 *  GCT:总垃圾收集时间
 *
 * -gcutil:垃圾收集统计信息
 *  S0:s0区的使用率
 *  S1:s1区的使用率
 *  E:eden区使用率
 *  O:老年代使用率
 *  M:元空间使用率
 *  CCS:压缩类空间使用率
 *  YGC:新生代gc次数
 *  YGCT:新生代gc耗费时间
 *  FGC:full gc次数
 *  FGCT:full gc耗费时间
 *  GCT:总垃圾收集时间
 */
public class Demo2_jstat {
    // -Xms20M -Xmx20M -Xmn10M -XX:+UseSerialGC -XX:+PrintGCDetails -verbose:gc
    public static void main(String[] args) throws IOException {
        final int _1MB = 1024 * 1024;
        // 申请2M的空间
        byte[] b1 = new byte[2 * _1MB];
        System.out.println("1111");
        System.in.read();

        // 申请2M的空间
        byte[] b2 = new byte[2 * _1MB];
        System.out.println("2222");
        System.in.read();

        // 申请2M的空间
        byte[] b3 = new byte[2 * _1MB];
        System.out.println("3333");
        System.in.read();
    }
}

启动上面的程序之前,先指定一些jvm参数

-Xms20M -Xmx20M -Xmn10M -XX:+UseSerialGC -XX:+PrintGCDetails -verbose:gc

image-20220316211331049-7436412.png

然后启动demo

image-20220316211628805-7436590.png

刚启动时,程序会申请2M的数组,可以看到eden区的使用率是62.37%,ygc的值为0。

让程序申请第二个2M的数组,之后再查看堆内存信息

image-20220316211756657-7436677.png

可以发现eden区的使用率达到了87.37%。

让程序申请第三个2M的数组,发现控制台输出提示

image-20220316211928010-7436769.png

再查看堆内存信息

image-20220316211957942-7436799.png

发现eden区的使用率降到了26.71,s1区使用率时70.80%,老年代的使用率是40%即4096kb也就是4M空间大小。同时ygc的次数是1,说明进行了一次ygc,并且把大对象放进了老年代中。

1.3 jinfo - Configuration Info For Java

作用:实时的查看和调整虚拟机各项参数。

命令格式:jinfo [option] <pid>

参数说明

第一个参数:option。

  • no options:输出全部的参数和系统属性。

  • -flag name:输出对应名称的参数。

  • -flag [+ | -] name:开启或者关闭对应名称的参数。

  • -flag name=value:设置对于名称参数的参数值。

  • -flags:输出全部的参数。

  • -sysprops:输出全部的系统属性。

命令演示

  • jinfo pid

  • jinfo -flag PrintGCDetails pid (输出PrintGCDetails参数的值)

  • jinfo -flag +PrintGCDetails pid (开启PrintGCDetails参数)

  • jinfo -flag HeapDumpPath=/ pid (设置HeapDumpPath参数的值)

在我电脑上使用这个命令会报错,据说是macOS的一个bug,需要升级到jdk9,懒得升了。bug链接

image-20220316233053701-7444655.png

1.4 jmap - Memory Map For Java

作用:一个多功能的命令,它可以生成Java程序的dump文件,也可以查看堆内对象的信息、classloader的信息和finalizer队列。

命令格式:jmap [option] <pid>

参数解释

第一个参数:option

  • no option:查看进程的内存镜像信息。

  • -heap:显示Java堆详细信息。

  • -histo[:live]:显示堆中对象的统计信息。

  • -clstats:打印类加载器信息。

  • -finalizerinfo:显示在F-Queue队列等待Finalizer线程执行finaizer方法的对象。

  • -dump:<dump-options>:生成堆转储快照。

命令演示

  • jamp pid。打印出虚拟机加载的每个共享对象的起始地址、映射大小以及共享对象文件的路径全称。

  • jamp -heap pid。打印一个堆的摘要信息,包括使用的GC算法、堆配置信息和各内存区域内存使用信息。

  • jmap -histo:live pid。显示堆中对象的统计信息,包括每个Java类型,对象数量,内存大小(单位字节),完全限定的类名。打印的虚拟机内部的类名称将会带一个‘*’前缀。如果指定了live子选项,则只计算活动的对象。

  • jmap -clstats pid。打印Java堆内存的永久保存区域的类加载器的智能统计信息。对于每个类加载器而言,它的名称、活跃度、地址、父类加载器、它所加载的类的数量和大小都会打印。

  • jmap -finalizerinfo pid。打印等待终结的对象信息。Number of objects pending for finalization: 0说明没有等待终结的对象。

  • jamp -dump:live,format=b,file=./jmap.bin pid。以二进制格式转储java堆到指定路径下的filename文件中。指定了live子选项,则只会转储活动的对象。

在macOS上使用这个命令同样也会报错。但某些命令还是可以的,比如dump二进制文件。

1.5 jhat - JVM Heap Dump Browser

作用:与jmap搭配使用,用来分析jmap生成的堆转储文件。jhat内置了一个微型的http/html服务器,生成dump文件的分析结果后,可以在浏览器中查看。

不过这个工具一般比较少使用,一是因为功能比较简陋,VisualVM和MAT等工具完全能够替代它。二是因为我们一般不会在生产服务器上直接去dump二进制文件,并且分析二进制文件是一个比较耗时的工作,所以就没必要使用命令行工具了。

命令格式:jhat [options] 堆转储文件

参数解释

第一个参数:option

  • [-stack <bool>]:开关对象分配调用栈跟踪,如果分配位置信息在堆转储中不可用,则必须将此标志设置为false,默认为true。

  • [-refs <bool>]:开关对象引用跟踪,默认为true。默认情况下,返回的指针是指向其他特定对象的对象。如果为false则会统计堆中所有对象。

  • [-port <port>]:设置jhat http server的端口号,默认为7000。

  • [-exclude <file>]:指定对象查询时需要排除的数据成员列表文件。例如,如果文件列出了java.lang.String.value,那么当从某个对象Object o计算可达的对象列表时,引用路径涉及java.lang.String.value的都会排除。

  • [-baseline <file>]:指定一个基准堆转储。在两个heap dumps中有相同object ID的对象会被标记为不是新的,其他对象会被标记为新的(new),在比较两个不同的堆转储时有用。

  • [-debug <int>]:设置debug级别,0表示不输出调试信息,值越大表示输出的调试信息越详细。[0, 1, 2]

  • [-version]:启动后只显示版本信息就退出。

第二个参数:堆转储文件。

命令演示

我们可以先生成一个二进制文件。

image-20220317201604307-7519365.png

然后使用jhat命令进行分析

image-20220317202140820-7519702.png

浏览器访问8888端口

image-20220317202228672-7519750.png

1.6 jstack - Stack Trace For Java

作用:查看或导出Java应用程序中线程堆栈信息。

线程快照是当前Java虚拟机内每一条线程正在执行的方法堆栈的集合。生成线程快照的主要目的是定位线程出现长时间停顿的原因,比如线程死锁、死循环、长时间等待外部资源等。线程出现停顿的时候通过jstack来查看各个线程的调用堆栈,就可以知道没有响应的线程到底在后台做什么事情,或者等待什么资源。如果Java程序崩溃生成core文件,jstack工具可以用来获得core文件的java stack和native stack的信息,从而轻松地知道Java程序是如何崩溃和在程序何处发生问题。另外jstack工具还可以附属到正在运行的java程序中,看到当时运行的java程序的java stack和native stack信息。

命令格式:jstack [options] <pid>

参数说明

第一个参数:options

  • -F:当线程挂起时,使用jstack -l pid请求不被响应时,强制输出线程堆栈。

  • -l:除堆栈外,显示关于锁的附加信息,比如ownable synchronizers。

  • -m:可以同时输出java以及C/C++的堆栈信息。

命令演示:

import java.util.concurrent.locks.Lock;
import java.util.concurrent.locks.ReentrantLock;

public class Demo5_jstack {
    public static void main(String[] args) {
        System.out.println("start");
        test1();
        // test2();
        System.out.println("end");
    }

    // 测试死循环
    private static void test1() {
        while (true) {}
    }

    // 测试死锁
    private static void test2() {
        Lock lock1 = new ReentrantLock();
        Lock lock2 = new ReentrantLock();

        new Thread(() -> {
            try {
                lock1.lock();
                Thread.sleep(100);
                lock2.lock();
            } catch (InterruptedException e) {
                e.printStackTrace();
            }
        }, "thread1").start();

        new Thread(() -> {
            try {
                lock2.lock();
                Thread.sleep(100);
                lock1.lock();
            } catch (InterruptedException e) {
                e.printStackTrace();
            }
        }, "thread2").start();
    }
}

测试死循环

启动程序后,top命令查看进程状态

image-20220317215050067-7525051.png

Linux系统中可以使用top -Hp 50242命令查看进程下的线程信息,但在macOS上不支持这个命令。我也没找到怎么查看macOS里进程下所有线程的方式==。

一般在Linux上的步骤就是下面这几步:

(1)top查看哪个进程cpu最高。

(2)top -Hp pid查看进程下面哪个线程cpu最高。

(3)jstack -l pid打印出进程的堆栈信息,然后将占有cpu最高的线程id转换为16进制,将这个16进制在堆栈信息中查询它的位置,一般都能定位到具体的代码位置。

image-20220319223044483-7700245.png

测试死锁

调用test2方法,然后启动程序,使用jstack -l pid命令能够打印出死锁信息。

image-20220319223352905-7700434.png

2、一些可视化分析工具

2.1 jConsole

使用jConsole可以查看程序的堆内存使用量、线程信息、CPU使用信息等。

在控制台输入jconsole命令,选择我们本地的程序

image-20220319225123482-7701484.png

进入后就能看到一些基本信息了

image-20220319225234676-7701555.png

在内存模块,我们可以查看新生代、老年代、元空间等区域的使用率

image-20220319225322645-7701603.png

在线程模块,我们能看到该进程下的所有线程,同时还能检测死锁

image-20220319225553294.png
image-20220319225626134-7701787.png

在VM概要模块,则可以看到本机的一些JVM信息。

2.2 Visual VM

作用:是到目前为止随JDK发布的功能最强大的运行监视和故障处理程序。官方在VisualVM的软件说明中写上了“All-in-One”的描述,说明它除了运行监视、故障处理外,还提供了很多其他方面的功能。如性能分析,VisualVM的性能分析甚至比很多专业的收费工具都好用,而且VisualVM不需要被监视的程序基于特殊的运行,因此它对应用程序的实际性能的影响很小,使得它可以直接应用在生产环境中。

VisualVM基于NetBeans平台开发,因此它一开始就具备了插件扩展功能的特性,通过插件扩展支持,VisualVM可以做到:

  • 显示虚拟机进程以及进程的配置、环境信息(jps、jinfo)

  • 监视应用程序的CPU、GC、堆、方法区以及线程的信息(jstat、jstack)

  • dump以及分析堆转储快照(jmap、jhat)

  • 方法级的程序运行性能分析,找到被调用最多、运行时间最长的方法。

  • 离线程序快照:收集程序的运行时配置、线程dump、内存dump等信息建立一个快照。

  • 动态的安装plugins。

使用:在控制台输入jvisualvm执行即可。进入后主页还会有一些文档,十分贴心。

image-20220320101903777-7742745.png

在工具栏可以进行插件的安装

image-20220320102033691-7742835.png

可以看到左侧可以选择本地进程或者远程的进程,选择我们的目标程序,顶部有概述、监视、线程、抽样器、Profiler几个选项。

image-20220320102219173-7742940.png

监视页面和jconsole的也有点像,不过在visualvm中可以直接进行堆dump文件分析

image-20220320102604420-7743165.png

在线程页面,还可以检测程序的死锁,进行线程dump的分析

image-20220320102722247-7743243.png

还有很多的功能大家可以一一去看看。以前还不知道JDK自带了这么多性能分析利器啊,以后遇到一些性能问题可以尝试使用一下上面的工具,也不需要额外安装。

文章首发于我的公众号【秃头哥编程】,欢迎大家关注。

©著作权归作者所有,转载或内容合作请联系作者
  • 序言:七十年代末,一起剥皮案震惊了整个滨河市,随后出现的几起案子,更是在滨河造成了极大的恐慌,老刑警刘岩,带你破解...
    沈念sama阅读 212,332评论 6 493
  • 序言:滨河连续发生了三起死亡事件,死亡现场离奇诡异,居然都是意外死亡,警方通过查阅死者的电脑和手机,发现死者居然都...
    沈念sama阅读 90,508评论 3 385
  • 文/潘晓璐 我一进店门,熙熙楼的掌柜王于贵愁眉苦脸地迎上来,“玉大人,你说我怎么就摊上这事。” “怎么了?”我有些...
    开封第一讲书人阅读 157,812评论 0 348
  • 文/不坏的土叔 我叫张陵,是天一观的道长。 经常有香客问我,道长,这世上最难降的妖魔是什么? 我笑而不...
    开封第一讲书人阅读 56,607评论 1 284
  • 正文 为了忘掉前任,我火速办了婚礼,结果婚礼上,老公的妹妹穿的比我还像新娘。我一直安慰自己,他们只是感情好,可当我...
    茶点故事阅读 65,728评论 6 386
  • 文/花漫 我一把揭开白布。 她就那样静静地躺着,像睡着了一般。 火红的嫁衣衬着肌肤如雪。 梳的纹丝不乱的头发上,一...
    开封第一讲书人阅读 49,919评论 1 290
  • 那天,我揣着相机与录音,去河边找鬼。 笑死,一个胖子当着我的面吹牛,可吹牛的内容都是我干的。 我是一名探鬼主播,决...
    沈念sama阅读 39,071评论 3 410
  • 文/苍兰香墨 我猛地睁开眼,长吁一口气:“原来是场噩梦啊……” “哼!你这毒妇竟也来了?” 一声冷哼从身侧响起,我...
    开封第一讲书人阅读 37,802评论 0 268
  • 序言:老挝万荣一对情侣失踪,失踪者是张志新(化名)和其女友刘颖,没想到半个月后,有当地人在树林里发现了一具尸体,经...
    沈念sama阅读 44,256评论 1 303
  • 正文 独居荒郊野岭守林人离奇死亡,尸身上长有42处带血的脓包…… 初始之章·张勋 以下内容为张勋视角 年9月15日...
    茶点故事阅读 36,576评论 2 327
  • 正文 我和宋清朗相恋三年,在试婚纱的时候发现自己被绿了。 大学时的朋友给我发了我未婚夫和他白月光在一起吃饭的照片。...
    茶点故事阅读 38,712评论 1 341
  • 序言:一个原本活蹦乱跳的男人离奇死亡,死状恐怖,灵堂内的尸体忽然破棺而出,到底是诈尸还是另有隐情,我是刑警宁泽,带...
    沈念sama阅读 34,389评论 4 332
  • 正文 年R本政府宣布,位于F岛的核电站,受9级特大地震影响,放射性物质发生泄漏。R本人自食恶果不足惜,却给世界环境...
    茶点故事阅读 40,032评论 3 316
  • 文/蒙蒙 一、第九天 我趴在偏房一处隐蔽的房顶上张望。 院中可真热闹,春花似锦、人声如沸。这庄子的主人今日做“春日...
    开封第一讲书人阅读 30,798评论 0 21
  • 文/苍兰香墨 我抬头看了看天上的太阳。三九已至,却和暖如春,着一层夹袄步出监牢的瞬间,已是汗流浃背。 一阵脚步声响...
    开封第一讲书人阅读 32,026评论 1 266
  • 我被黑心中介骗来泰国打工, 没想到刚下飞机就差点儿被人妖公主榨干…… 1. 我叫王不留,地道东北人。 一个月前我还...
    沈念sama阅读 46,473评论 2 360
  • 正文 我出身青楼,却偏偏与公主长得像,于是被迫代替她去往敌国和亲。 传闻我的和亲对象是个残疾皇子,可洞房花烛夜当晚...
    茶点故事阅读 43,606评论 2 350

推荐阅读更多精彩内容