JDK-7198130 : G1: PrintReferenceGC output comes out of order
  • Type: Bug
  • Component: hotspot
  • Sub-Component: gc
  • Affected Version: 8
  • Priority: P4
  • Status: Resolved
  • Resolution: Fixed
  • OS: generic
  • CPU: generic
  • Submitted: 2012-09-12
  • Updated: 2013-09-18
  • Resolved: 2012-09-25
The Version table provides details related to the release that this issue/RFE will be addressed.

Unresolved : Release in which this issue/RFE will be addressed.
Resolved: Release in which this issue/RFE has been resolved.
Fixed : Release in which this issue/RFE has been fixed. The release containing this fix may be available for download as an Early Access Release or a General Availability Release.

To download the current JDK release, click here.
JDK 7 JDK 8 Other
7u40Fixed 8Fixed hs24Fixed
Description
In the G1 output the placement of the Reference
processing information has moved.

older JDK7 result:

3.746: [GC pause (young)3.802: [SoftReference, 0 refs, 0.0000090 secs]3.802: [WeakReference, 148 refs, 0.0000400 secs]3.802: [FinalReference, 13020 refs, 0.0462290 secs]3.848: [PhantomReference, 7 refs, 0.0000110 secs]3.848: [JNI Weak Reference, 0.0000150 secs], 0.10515900 secs]
   [Parallel Time:  52.2 ms]

JDK8 result:

3.648: [SoftReference, 0 refs, 0.0000110 secs]3.648: [WeakReference, 207 refs, 0.0000440 secs]3.648: [FinalReference, 14473 refs, 0.0499260 secs]3.698: [PhantomReference, 0 refs, 0.0000050 secs]3.698: [JNI Weak Reference, 0.0000120 secs]3.700: [GC pause (G1 Evacuation Pause) (young), 0.0853910 secs]
   [Parallel Time: 31.9 ms, GC Workers: 8]

In 7 the first timestamp on the GC line is before the "[GC pause".  In
8 the first timestamp is before the "[SoftReference".
Comments
EVALUATION Incorrect placement of the start of the GC log output record.
12-09-2012