NetBeans Forums
| View previous topic :: View next topic |
| Author |
Message |
Locked-Spamcollect6
Posted via mailing list.
|
 Posted: Wed Feb 04, 2009 1:55 pm Post subject: Time of sub tree in Call Tree doesn't sum up correctly? |
 |
|
Hello,
we're currently examining an application in order to find bottlenecks of processing time.
Today I came across a result that I cannot interpret.
After profiling I opened the Call Tree View and saw a call tree like this:
Call Tree - Method Time Invocations
main 67189ms (100%) 1
foo 67189ms (100%) 9542
bar 64521ms (96%) 104413
foo1 13727ms (20,4%) 25472576
Self time 11957ms (17,8%) 104413
foo2 277ms (0,4%) 104413
foo99 1416ms (2,1%) 9542
.....
Profiling was done locally with "foo()" as root method.
The question now:
bar() takes about 65s but its sub methods foo1, self time and foo2 take only 14s+12s+0,3s = ~26s.
Where did the remaining ~39s go? I thought the time of bar() must be the sum of its sub calls.
What am I missing here?
Any help would be greatly appreciated.
Regards,
Franz
--
Jetzt 1 Monat kostenlos! GMX FreeDSL - Telefonanschluss + DSL
f |
|
| Back to top |
|
 |
locked
Joined: 06 Feb 2009
Posts: 1
|
| Posted: Fri Feb 06, 2009 10:20 am Post subject: Re: Time of sub tree in Call Tree doesn't sum up correctly? |
|
|
Sorry, on the forums, there's no indentation. Here's the call tree again with better indentation.
| Code: | Call Tree - Method Time Invocations
main 67189ms (100%) 1
..foo 67189ms (100%) 9542
....bar 64521ms (96%) 104413
......foo1 13727ms (20,4%) 25472576
......Self time 11957ms (17,8%) 104413
......foo2 277ms (0,4%) 104413
....foo99 1416ms (2,1%) 9542
|
The question remains: bar() is using ~64,5s, but the subcalls (foo1, self time and foo2) only add up to about 26s.
Are the remaining 39s consumed by profiler overhead or how do I have to interpret the output? |
|
| Back to top |
|
|
Tomas Hurka
Posted via mailing list.
|
| Posted: Fri Feb 06, 2009 11:22 am Post subject: Time of sub tree in Call Tree doesn't sum up correctly? |
|
|
Hi Franz,
On 6 Feb 2009, at 11:20, locked wrote:
| Quote: | Sorry, on the forums, there's no indentation. Here's the call tree
again with better indentation.
Code:
Call Tree - Method Time Invocations
main 67189ms (100%) 1
..foo 67189ms (100%) 9542
....bar 64521ms (96%) 104413
......foo1 13727ms (20,4%) 25472576
......Self time 11957ms (17,8%) 104413
......foo2 277ms (0,4%) 104413
....foo99 1416ms (2,1%) 9542
The question remains: bar() is using ~64,5s, but the subcalls (foo1,
self time and foo2) only add up to about 26s.
Are the remaining 39s consumed by profiler overhead or how do I have
to interpret the output?
|
I don't know exactly, but one of the reasons could be inaccurate
calibration data. If you combine that with the fact that foo1 is
invoked over 24 millions, you can see the above difference. I would
suggest to rerun calibration of the JDK used for profiling (Profile-
| Quote: | Advanced Commands->Run Profiler Calibration). With new calibration
|
data, run the profiling again and see if it makes any difference.
Bye,
--
Tomas Hurka <mailto:address-removed>
NetBeans Profiler http://profiler.netbeans.org
VisualVM http://visualvm.dev.java.net
Software Engineer, Developer Platforms Group
Sun Microsystems, Praha Czech Republic
---------------------------------------------------------------------
To unsubscribe, e-mail: address-removed
For additional commands, e-mail: address-removed |
|
| Back to top |
|
|
|
|
You cannot post new topics in this forum
You cannot reply to topics in this forum
You cannot edit your posts in this forum
You cannot delete your posts in this forum
You cannot vote in polls in this forum
You can attach files in this forum
You can download files in this forum
|
|
FAQ
Search
Memberlist
Register
Profile
Log in to check your private messages
Log in
