2017-09-28 1 views
0

저는 G1GC의 작동 방식을 이해할 수 있도록 테스트 Java 프로그램을 만들었습니다.G1GC - 복합 GC없이 Tenured를 어떻게 정리할 수 있습니까?

내 프로그램에는 두 개의 스레드가 있습니다. 하나는 약 200MB의 개체 (단지 1000 개의 문자열)로 구성된 큰 ArrayList를로드 한 다음 요소를 제거하고 루프에서 잠을 자며 요소를 추가합니다. 아이디어는 천천히 오래 가고 Tenured로 끝날 것입니다.

다른 스레드는 GC 실행을 유발하기 위해 많은 쓰레기를 만들기 위해 while 루프에 큰 문자열을 만듭니다.

이 모든 것이 정상적으로 작동하는 것으로 보이지만, 혼란스러워하는 점은 소유하고있는 GC가 혼합 된 GC가없는 상태에서 정리 된 것입니다.

[Eden: 581.0M(581.0M)->0.0B(573.0M) Survivors: 9216.0K->9216.0K Heap: 904.3M(1024.0M)->330.9M(1024.0M)] 
[Times: user=0.03 sys=0.00, real=0.00 secs] 
2017-09-28T16:22:14.889+0000: 2.415: 2017-09-28T16:22:14.889+0000[GC concurrent-root-region-scan-start] 
: 2.415: Total time for which application threads were stopped: 0.0081917 seconds, Stopping threads took: 0.0000852 seconds 
2017-09-28T16:22:14.889+0000: 2.415: [GC concurrent-root-region-scan-end, 0.0002779 secs] 
2017-09-28T16:22:14.889+0000: 2.415: [GC concurrent-mark-start] 
2017-09-28T16:22:14.904+0000: 2.430: [GC concurrent-mark-end, 0.0143310 secs] 
2017-09-28T16:22:14.905+0000: 2.431: [GC remark 2017-09-28T16:22:14.905+0000: 2.431: [Finalize Marking, 0.0003653 secs] 2017-09-28T16:22:14.905+0000: 2.431: [GC ref-proc2017-09-28T16:22:14.905+0000: 2.431: [SoftReference, 0 refs, 0.0008964 secs]2017-09-28T16:22:14.906+0000: 2.432: [WeakReference, 0 refs, 0.0005380 secs]2017-09-28T16:22:14.907+0000: 2.433: [FinalReference, 2 refs, 0.0005460 secs]2017-09-28T16:22:14.907+0000: 2.434: [PhantomReference, 0 refs, 0 refs, 0.0010243 secs]2017-09-28T16:22:14.908+0000: 2.435: [JNI Weak Reference, 0.0000090 secs], 0.0034419 secs] 2017-09-28T16:22:14.909+0000: 2.435: [Unloading, 0.0008396 secs], 0.0057362 secs] 
[Times: user=0.03 sys=0.00, real=0.01 secs] 
2017-09-28T16:22:14.911+0000: 2.437: Total time for which application threads were stopped: 0.0069810 seconds, Stopping threads took: 0.0011536 seconds 
2017-09-28T16:22:14.911+0000: 2.437: [GC cleanup 418M->302M(1024M), 0.0014100 secs] 
[Times: user=0.00 sys=0.00, real=0.00 secs] 
2017-09-28T16:22:14.912+0000: 2.438: Total time for which application threads were stopped: 0.0015190 seconds, Stopping threads took: 0.0000697 seconds 
2017-09-28T16:22:14.912+0000: 2.438: [GC concurrent-cleanup-start] 
2017-09-28T16:22:14.912+0000: 2.439: [GC concurrent-cleanup-end, 0.0000987 secs] 
2017-09-28T16:22:14.987+0000: 2.513: [GC pause (G1 Evacuation Pause) (young) 2.513: [G1Ergonomics (CSet Construction) start choosing CSet, _pending_cards: 4816, predicted base time: 5.38 ms, remaining time: 44.62 ms, target pause time: 50.00 ms] 
2.513: [G1Ergonomics (CSet Construction) add young regions to CSet, eden: 573 regions, survivors: 9 regions, predicted young region time: 3.54 ms] 
2.513: [G1Ergonomics (CSet Construction) finish choosing CSet, eden: 573 regions, survivors: 9 regions, old: 0 regions, predicted pause time: 8.92 ms, target pause time: 50.00 ms] 
2017-09-28T16:22:14.991+0000: 2.517: [SoftReference, 0 refs, 0.0010742 secs]2017-09-28T16:22:14.992+0000: 2.518: [WeakReference, 0 refs, 0.0006150 secs]2017-09-28T16:22:14.992+0000: 2.519: [FinalReference, 0 refs, 0.0004760 secs]2017-09-28T16:22:14.993+0000: 2.519: [PhantomReference, 0 refs, 0 refs, 0.0008707 secs]2017-09-28T16:22:14.994+0000: 2.520: [JNI Weak Reference, 0.0000094 secs] 2.521: [G1Ergonomics (Mixed GCs) do not start mixed GCs, reason: reclaimable percentage not over threshold, candidate old regions: 14 regions, reclaimable: 13071400 bytes (1.22 %), threshold: 5.00 %] 
, 0.0077749 secs] 
    [Parallel Time: 3.6 ms, GC Workers: 16] 
     [GC Worker Start (ms): Min: 2513.1, Avg: 2513.6, Max: 2515.2, Diff: 2.1] 
     [Ext Root Scanning (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.1, Sum: 0.9] 
     [Update RS (ms): Min: 0.0, Avg: 0.4, Max: 1.1, Diff: 1.1, Sum: 6.3] 
     [Processed Buffers: Min: 0, Avg: 1.2, Max: 4, Diff: 4, Sum: 20] 
     [Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.1, Sum: 0.8] 
     [Code Root Scanning (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1] 
     [Object Copy (ms): Min: 1.2, Avg: 2.3, Max: 2.9, Diff: 1.7, Sum: 36.9] 
     [Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 2.1] 
     [Termination Attempts: Min: 1, Avg: 1.1, Max: 2, Diff: 1, Sum: 18] 
     [GC Worker Other (ms): Min: 0.0, Avg: 0.0, Max: 0.1, Diff: 0.0, Sum: 0.3] 
     [GC Worker Total (ms): Min: 1.4, Avg: 3.0, Max: 3.5, Diff: 2.1, Sum: 47.3] 
     [GC Worker End (ms): Min: 2516.5, Avg: 2516.6, Max: 2516.6, Diff: 0.1] 
    [Code Root Fixup: 0.0 ms] 
    [Code Root Purge: 0.0 ms] 
    [Clear CT: 0.2 ms] 
    [Other: 4.0 ms] 
     [Choose CSet: 0.0 ms] 
     [Ref Proc: 3.4 ms] 
     [Ref Enq: 0.2 ms] 
     [Redirty Cards: 0.1 ms] 
     [Humongous Register: 0.0 ms] 
     [Humongous Reclaim: 0.0 ms] 
     [Free CSet: 0.2 ms] 
    [Eden: 573.0M(573.0M)->0.0B(607.0M) Survivors: 9216.0K->7168.0K Heap: 787.9M(1024.0M)->221.4M(1024.0M)] 

이것은 GC 로그 스 니펫입니다. 이 실행 사이에 다음 사항을 유의하십시오.

[Eden: 581.0M(581.0M)->0.0B(573.0M) Survivors: 9216.0K->9216.0K Heap: 904.3M(1024.0M)->330.9M(1024.0M)] 
... 
[Eden: 573.0M(573.0M)->0.0B(607.0M) Survivors: 9216.0K->7168.0K Heap: 787.9M(1024.0M)->221.4M(1024.0M)] 

따라서이 실행에서는 사용량이 줄었습니다. 어떻게 종신 공간이 혼합 된 (또는 전체) 발생 GC없이 청소지고 -

[G1Ergonomics (Mixed GCs) do not start mixed GCs, reason: reclaimable percentage not over threshold, candidate old regions: 14 regions, reclaimable: 13071400 bytes (1.22 %), threshold: 5.00 %] 

그래서 내 질문은 : 그러나, 혼합 GC의 언급이 가리키고 모든 그것입니다 제공, 없다?

답변

1

동시주기에 의해 완전히 참조 해제되지 않은 것으로 판명 된 이전 영역을 대피시키기 위해 혼합 된 콜렉션이 필요하지 않습니다. 22 :

2017-09-28T16

14.911 + 0000 : 2.437 : GC 정리 418M-> 302M (1024M) 0.0014100 초]

추가적인 기록 플래그가 있는데, 예를 들어 -XX:+G1PrintHeapRegions으로, 이러한 정리를 추적하는 데 사용할 수 있습니다. 자세한 설명은 G1GC 로그의 oracle blog을 참조하십시오.

+0

감사합니다. 나는이 예외적 인 경우를 맞이했다. 내 코드가 내 ObjectList에 대한 약간의 휘젓기를 만들 때 내 ArrayList의 첫 번째 요소를 삭제하고 마지막에 새로운 요소를 추가했기 때문이다. 내가 시작할 때 ArrayList를 채울 때, JVM은 전체 영역까지 한 영역의 객체를 할당한다고 생각합니다. 내가 요소를 무작위로 추출하자마자 혼합 된 GC가 예상대로 나타납니다. –

관련 문제