• 性能分析系列-小命令保证大性能


    近期在工作中经常和性能压測工作打交道,积累了一些性能分析经验。我认为这些经验对每个开发人员都有帮助的,能开发出性能高的代码也是我们的终于目标。

    由易到难,我们逐步介绍不同命令的使用方法和优点,这些命令是怎样帮助我们开发人员进行性能分析的。

    一、开发人员的自測利器-Hprof命令

    1、演示样例演示

    样例程序:

    /**
     * PROJECT_NAME: test
     * DATE:         16/7/22
     * CREATE BY:    chao.cheng
     **/
    public class HProfTest {
        public void slowMethod() {
            try {
                Thread.sleep(1000);
            } catch (Exception e) {
                e.printStackTrace();
            }
        }
    
        public void slowerMethod() {
            try {
                Thread.sleep(10000);
            } catch (Exception e) {
                e.printStackTrace();
            }
        }
    
        public static void main(String[] args) {
            HProfTest test = new HProfTest();
            test.slowerMethod();
            test.slowMethod();
        }
    }

    注:这是一段測试代码通过sleep方法进行延时,在程序执行过程中很慢,我想知道究竟是哪段程序影响的总体性能呢?

    我在这个java程序中。加了例如以下执行參数:

    -agentlib:hprof=cpu=times,interval=10
    /* 
        times:java函数的执行时间
        hprof=cpu是针对cpu统计时间
        interval=10 採样10次 
    */

    再次执行这段程序显演示样例如以下图:

    这里写图片描写叙述
    这时候还发如今project文件夹里面,多了一个文本文件java.hprof.txt,例如以下图所看到的:

    这里写图片描写叙述
    内容例如以下:

    CPU TIME (ms) BEGIN (total = 11542) Fri Jul 22 11:00:34 2016
    rank   self  accum   count trace method
       1 86.65% 86.65%       1 303422 com.test.HProfTest.slowerMethod
       2  8.66% 95.31%       1 303423 com.test.HProfTest.slowMethod
       3  0.25% 95.56%      36 300745 java.util.zip.ZipFile.<init>
       4  0.20% 95.76%      36 300434 java.lang.String.equals
       5  0.13% 95.89%      14 301138 java.net.URLStreamHandler.parseURL
       6  0.11% 96.01%       6 301339 java.net.URLClassLoader$1.run
       7  0.10% 96.10%      14 301124 java.lang.String.<init>
       8  0.09% 96.19%    3407 300355 java.lang.String.charAt
       9  0.08% 96.27%      36 300443 java.io.UnixFileSystem.normalize

    注:通过上面内容能够看到,哪个类的方法执行时间长。耗费了cpu时间,一目了然,方便我们高速定位问题。

    2、命令的详细解说
    hprof不是独立的监控工具。它仅仅是一个java agent工具,它能够用在监控Java应用程序在执行时的CPU信息和堆内容。使用java -agentlib:hprof=help命令能够查看hprof的使用文档。

    这里写图片描写叙述

    通过上图能够看到这个工具很强大。能够统计的东西许多,上面的样例统计的是cpu时间,相同我们还能够统计内存占用的dump信息。
    如:-agentlib:hprof=heap,format=b,file=/test.hprof

    这个hprof小工具,很方便我们在用JUnit自測代码的时候结合使用,既能够解决业务上的BUG,又能够在一定程序上解决可发现的性能问题。很有用。

    二、性能排查工具-pidstat

    1、演示样例演示
    样例程序:

    /**
     * PROJECT_NAME: test
     * DATE:         16/7/22
     * CREATE BY:    chao.cheng
     **/
    public class PidstatTest {
        public static class PidstatTask implements  Runnable {
            public void run() {
                while(true) {
                    double value = Math.random() * Math.random();
                }
            }
        }
    
        public static class LazyTask implements Runnable {
            public void run() {
                try {
                    while (true) {
                        Thread.sleep(1000);
                    }
                } catch (Exception e) {
                    e.printStackTrace();
                }
            }
        }
    
        public static void main(String[] args) {
            new Thread(new PidstatTask()).start();
            new Thread(new LazyTask()).start();
            new Thread(new LazyTask()).start();
        }
    }

    注:这是一段測试用的java程序,将其执行起来。

    在命令行输入:

    pidstat -p 843 1 3 -u -t
    /* 
    -u:代表对cpu使用率的监控
    參数1 3:表示每秒採样一次,一共三次
    -t:将监控级别细化到线程 
    */

    执行命令显演示样例如以下图所看到的:
    这里写图片描写叙述
    注:事实上中TID就是线程ID,%usr表示用户线程使用率。从图中能够看到855这个线程占用cpu很的高。

    再输入例如以下命令:

    jstack -l 843 > /tmp/testlog.txt

    查看testlog.txt显演示样例如以下部分内容:

    这里写图片描写叙述
    注:我们关注的是日志文件的NID这个字段,它相应的就是我们上面说的TID,NID是TID的16进制表示,将上面的十进制855转换成十六进制为357。在日志中进行搜索看到例如以下内容:

    "Thread-0" prio=10 tid=0x00007f7d90103800 nid=0x357 runnable [0x00007f7d943d5000]
       java.lang.Thread.State: RUNNABLE
        at PidstatTest$PidstatTask.run(PidstatTest.java:13)
        at java.lang.Thread.run(Thread.java:722)
    
       Locked ownable synchronizers:
        - None

    以此能够判断出有性能瓶颈的程序点。

    2、pidstat详细命令详细解释
    pidstat是一个功能很强大的性能监測工具,他是Sysstat的组件之中的一个。能够从http://sebastien.godard.pagesperso-orange.fr/download.html 进行下载,下载后能够通过./configure等命令进行安装。这个命令的强大之处在于不仅能够监控进程的性能情况。也能够监控线程的性能情况。

    pidstat监控cpu经常使用显示字段内容例如以下:

    1、PID - 被监控的任务的进程号
    2%usr - 当在用户层执行(应用程序)时这个任务的cpu使用率,和 nice 优先级无关。注意这个字段计算的cpu时间不包含在虚拟处理器中花去的时间。
    3%system - 这个任务在系统层使用时的cpu使用率。
    4%guest - 任务花费在虚拟机上的cpu使用率(执行在虚拟处理器)。

    5%CPU - 任务总的cpu使用率。

    在SMP环境(多处理器)中,假设在命令行中输入-I參数的话。cpu使用率会除以你的cpu数量。 6、CPU - 正在执行这个任务的处理器编号。

    7、Command - 这个任务的命令名称。

    pidstat监控io经常使用的字段显示内容例如以下:

    1、kB_rd/s - 任务从硬盘上的读取速度(kb)
    2、kB_wr/s - 任务向硬盘中的写入速度(kb)
    3、kB_ccwr/s - 任务写入磁盘被取消的速率(kb)

    三、一个内存溢出案例分析

    1、内存溢出现象
    系统共同拥有8台server,每次随机仅仅有一台server报java.lang.OutOfMemoryError: GC overhead limit exceeded错误。然后接着就报内存溢出错误java.lang.OutOfMemoryError: Java heap space

    2、理论支撑
    我们先解释一下什么是GC overhead limit exceeded错误。

    GC overhead limt exceed检查是Hotspot VM 1.6定义的一个策略,通过统计GC时间来预測是否要OOM了,提前抛出异常,防止OOM发生。Sun 官方对此的定义是:“并行/并发回收器在GC回收时间过长时会抛出OutOfMemroyError。过长的定义是,超过98%的时间用来做GC而且回收了不到2%的堆内存。用来避免内存过小造成应用不能正常工作。

    能够看到当堆中的对象无法被收回的时候,就提前遇警报出这种错误,此时内存并没有溢出,这个特性在JDK中是默认加入的。

    3、DUMP文件分析
    将dump文件导入VisualVM工具中,例如以下图所看到的:

    这里写图片描写叙述

    通过上图能够看出类结构图中,最占用内存的是char[],LinkedHashMap和String三项。可是这三项的实例数并没有占满,看样子不会内存溢出。怎么才干详细分析呢?原因就在于GC overhead limt exceed。这个错并不会在内存真正溢出才会报,所以通过dump文件,我们仅仅能自己去判断分析,哪些项有可能会造成溢出。我们进入char[]项详细来看。会发现里面有许多hessian的url字符被缓存,通过排除程序能够看到由于底层中间件程序为了提高“性能”,将每次调用的url都缓存起来,不用每次都生成,但没有相应缓存释放操作。于是造成了大量字符对象长期持有从而报错,在此就不截图来详细看代码,涉及一些公司信息

    4、问题解决方式
    * 能够加入JVM的启动參数来去掉提前报警限制:-XX:-UseGCOverheadLimit。于其让应用每次都提前报警,还不如让暴风雨来的更猛些,直接内存溢出。由于server是集群,当中一台挂掉不会影响线上正常交易。同一时候也方便我们通过日志来排错。

    • 通过排查程序,检查系统是否有使用大内存的代码不释放或死循环。
  • 相关阅读:
    箭头函数
    对象的方法
    promise
    分布式文件存储FastDFS 邓维
    mysql字段长度设计 邓维
    计算机速成课 第二十一集 压缩
    计算机速成课 第二十四集 冷战和消费主义
    计算机速成课 第二十二集 命令行界面
    计算机速成课 第二十集 文件系统
    计算机速成课 第二十三集 屏幕&2D 图形显示
  • 原文地址:https://www.cnblogs.com/zhchoutai/p/8834045.html
Copyright © 2020-2023  润新知