Объяснение выписок журнала Tomcat GC

Question

Вот заявление от моего catalina.out, генерируемой мой сервер Tomcat:

1885.855: [GC [PSYoungGen: 742996K-> 64359K (761472K)] 2509583K-> 1832973K (4116928K), 0.1453610 сек.] [Times: user = 0.31 sys = 0.00, real = 0.14 secs]

Может ли кто-нибудь объяснить смысл различных чисел здесь?

Answer 1

Распад линии как followd:

1885.85 - это в секундах это количество времени, виртуальная машина работает уже, поэтому около 31 минут или около того, в вашем случае, в какой момент GC взял место.

[GC - указывает на то, что GC в этот момент времени. Это частичное GC, иногда он будет показывать Full GC

Теперь Total Heap доступны для JVM = Young + Старый

[PSYoungGen:742996K->64359K(761472K) - представляет собой параллельный Scavenging коллектор в молодое поколение (которое является одним из многих типы коллекторов ГХ, доступных в JVM).

Память освобождается с помощью ГХ всегда рисунка

x->y(z) 

х является начальной памяти до GC, у является память после того, как GC, г это общая память позволила этой области в JVM

так в вашем примере, 742996K->64359K(761472K) - общий Young размер 761Mb, и когда он достиг 742 Mb коллекция имела место, го она очищена до 64.3Mb т.е. она очищена до 678 Мб

2509583K->1832973K(4116928K) 

Здесь общий объем памяти кучи представлена. Таким образом, из общей возможной кучи 4.1 Гб, когда GC состоялся, он заполнил 2,5 Гб, и он опустился до 1,83 ГБ - снова тот же самый 678 МБ - это то, что было очищено.

0.1453610 secs] 

Вся эта операция заняла 0.1453610 секунд

[Times: user=0.31 sys=0.00, real=0.14 secs] 

показывает ломку user, system and real times приняты.

Это только одна линия - вы бы искать шаблон особ, который говорит Full GC в нем и использовать анализатор лог как GCViewer для показа вам пропускную способность и другие хорошие вещи.

Также читайте docs from Sun, чтобы получить основы.

Дальнейшее чтение:

http://sujitpal.blogspot.com/2006/08/charting-jvm-garbage-collection.html

Java Garbage Collection Log messages