RFR: JDK-8058944: Unify the time reporting strings in G1 remark (original) (raw)

Bengt Rutisson bengt.rutisson at oracle.com
Tue Mar 1 08:47:47 UTC 2016


Hi all,

Could I have a couple of reviews for this small cleanup to the G1 remark logging?

http://cr.openjdk.java.net/~brutisso/8058944/webrev.00/ https://bugs.openjdk.java.net/browse/JDK-8058944

Currently the remark logging looks like this on debug level:

[0,937s][info ][gc,start ] GC(2) Pause Remark (0,937s) [0,937s][debug][gc,start ] GC(2) Finalize Marking (0,937s) [0,955s][debug][gc ] GC(2) Finalize Marking (0,937s, 0,955s) 17,949ms [0,955s][debug][gc,start ] GC(2) GC Ref Proc (0,955s) [0,956s][debug][gc ] GC(2) GC Ref Proc (0,955s, 0,956s) 0,279ms [0,956s][debug][gc,start ] GC(2) Unloading (0,956s) [0,965s][debug][gc ] GC(2) Unloading (0,956s, 0,965s) 9,623ms [0,965s][debug][gc,start ] GC(2) GC Aggregate Data (0,965s) [0,966s][debug][gc ] GC(2) GC Aggregate Data (0,965s, 0,966s) 0,614ms [0,966s][info ][gc ] GC(2) Pause Remark 89M->89M(507M) (0,937s, 0,966s) 28,653ms

With the proposed change it will look like this:

[0,946s][info ][gc,start ] GC(2) Pause Remark (0,946s) [0,946s][debug][gc,start ] GC(2) Finalize Marking (0,946s) [0,960s][debug][gc ] GC(2) Finalize Marking (0,946s, 0,960s) 13,677ms [0,960s][debug][gc,start ] GC(2) Ref Proc (0,960s) [0,961s][debug][gc ] GC(2) Ref Proc (0,960s, 0,960s) 0,524ms [0,961s][debug][gc,start ] GC(2) Unloading (0,961s) [0,972s][debug][gc ] GC(2) Unloading (0,961s, 0,972s) 11,425ms [0,972s][debug][gc,start ] GC(2) Aggregate Data (0,972s) [0,972s][debug][gc ] GC(2) Aggregate Data (0,972s, 0,972s) 0,377ms [0,972s][info ][gc ] GC(2) Pause Remark 62M->62M(506M) (0,946s, 0,972s) 26,243ms

Thanks, Bengt



More information about the hotspot-gc-dev mailing list