Java VisualVM为CPU分析提供了奇怪的结果 - 还有其他人遇到过这种情况吗?
我已经编写了这个小的(而且效率极低的)类,并希望使用Java VisualVM对其进行分析。
public class Test {
public static void main(String[] args) throws IOException {
BufferedReader br = new BufferedReader(new InputStreamReader(System.in));
br.readLine();
int n = Integer.parseInt(args[0]);
int fib = fib(n);
System.out.println(fib);
}
private static int fib(int n) {
if (n < 2) {
return n;
}
return fib(n-1)+fib(n-2);
}
}
结果很奇怪。结果完全由对ConnectionHandler.run()的调用主导。
(98.2%) sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run()
(1.7%) java.lang.Thread.join(long)
(0%) java.lang.String.equals(Object)
etc...
大概有大约一百种方法被描述,其中没有一个是fib(int)!
不可思议的是,我的程序实际上把所有的时间都花在这些方法上。它们似乎是连接到我的jvm并执行其操作的探查器。
我做错了什么?
为清楚起见,已编辑:如果为 n 传入 45,则此应用程序将运行 20 个分析良好的秒。我最初分析的程序(不是斐波那契计算器)将CPU上的所有四个内核都固定在100%上,并且我进行的性能分析运行持续长达5分钟。这些具有相同的结果,并且我的应用程序中的方法在热点方法列表中没有出现在高位。
它因运行而异,但ConnectionHandler.run()始终位于顶部,通常占配置文件时间的99%左右。
第二次编辑:我尝试过使用采样器,现在我得到的结果与JProfiler正在产生的结果一致。这样做的缺点是,我无法获得分析附带的堆栈跟踪信息。但对于我的直接需求来说,这非常好。
我在玩游戏时发现的一点是,VisualVM 在分析方法调用时会计算方法调用的挂钟时间。
在我的特定情况下,我的应用程序有一个主线程,它启动工作线程并立即阻止队列上等待消息。
这意味着阻塞方法似乎几乎占用了探查器上的所有时间,尽管不是这种方法占用了我的CPU。
我希望 sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run() 方法也是如此,它很好地完成了它的工作 - 但是当它终止时,它成为我的应用程序中运行时间最长的方法之一 - 重复。