INTELLIGENT WORK FORUMS
FOR COMPUTER PROFESSIONALS

Log In

Come Join Us!

Are you a
Computer / IT professional?
Join Tek-Tips Forums!
  • Talk With Other Members
  • Be Notified Of Responses
    To Your Posts
  • Keyword Search
  • One-Click Access To Your
    Favorite Forums
  • Automated Signatures
    On Your Posts
  • Best Of All, It's Free!

*Tek-Tips's functionality depends on members receiving e-mail. By joining you are opting in to receive e-mail.

Posting Guidelines

Promoting, selling, recruiting, coursework and thesis posting is forbidden.

Jobs

GC Log Output

GC Log Output

(OP)
Can anyone explain this GC log line?

CODE

2014-04-22T12:20:41.635-0400: 469154.330: [GC 469154.330: 
[ParNew: 891798K->104831K(943744K), 6.7735900 secs] 2514763K->1808044K(8283776K), 21.4037500 secs] 
[Times: user=65.62 sys=0.00, real=21.40 secs] 

It's a new generation collection on a system using CMS. It's version 1.6.0_31.

According to this line, the Parallel New collection took 6.77 seconds, but the elapsed real time was 21.40 seconds. Also, under Times, the "user" time is 65.62. I'm not sure what these numbers are saying.

RE: GC Log Output

(OP)
Thanks for the links mikrom.

For the most part I understand "what" the different times are. What's confusing me is that I'm used to seeing them all pretty much within a fraction of a second apart from each other. What I'm trying to figure out is why there is such a huge difference in the numbers. Just trying to figure out why a GC needing 65.62 seconds of user CPU, reports that it took only 6.77 seconds, but also 21.0 real elapsed time.

I think I have the 65.62 seconds verses 6.77 seconds figured. I've got 12 threads assigned as GC threads, so if 12 threads were each working for 6+ seconds, that gets us in the neighborhood of 65.62 seconds. Not exact, but probably close enough considering overhead for thread coordination and such.

But, why is the elapsed time 21.4 seconds? I would think the threads would be acting in parallel.

My machine has 24 processors, so the threads shouldn't be competing for CPU resources.

RE: GC Log Output

Quote (SamBones)


But, why is the elapsed time 21.4 seconds?

I think, that here http://stackoverflow.com/questions/556405/what-do-... it's explained:

Quote:


As a result, on a multicore system, the user and/or sys time (as well as their sum) can actually exceed the real time.


RE: GC Log Output

(OP)
Thanks, that makes sense.

Red Flag This Post

Please let us know here why this post is inappropriate. Reasons such as off-topic, duplicates, flames, illegal, vulgar, or students posting their homework.

Red Flag Submitted

Thank you for helping keep Tek-Tips Forums free from inappropriate posts.
The Tek-Tips staff will check this out and take appropriate action.

Reply To This Thread

Posting in the Tek-Tips forums is a member-only feature.

Click Here to join Tek-Tips and talk with other members!

Resources

Close Box

Join Tek-Tips® Today!

Join your peers on the Internet's largest technical computer professional community.
It's easy to join and it's free.

Here's Why Members Love Tek-Tips Forums:

Register now while it's still free!

Already a member? Close this window and log in.

Join Us             Close