Есть dll, пользуюсь jna для работы с ней из java
Все работает до тех пор, пока не начинает заканчиваться свободная память
jre в таких случаях запускает сборку мусора, но если память пытается аллокировать dll - сборка не запускается,
что приводит к падению по нехватке памяти
Воркэраундом повставлял принудительный System.gc() перед каждым вызовом функций dll.
Тупо, но работает.
Хотелось бы более грамотное решение этой проблемы.

BlazkowiczЕсли нативный код занимает память, то он и должен её освобождать.

Само собой
Проблема в том, чтобы выделить память в dll, когда вся она занята мусором из jre

BlazkowiczДля привязки нативных ресурсов к Java к времени жизни Java объектов используют PhantomReference

эта привязка не требуется
dll и java-код вообще практически не взаимодействуют друг с другом на уровне данных

Пример.
Выделено Xmx10m
Запустился java-код, поработал, съел все доступные 10m
Допустим, из этих 10ти "живых" объектов - только 2m, остальное мусор, ждущий уборки gc'ом
Если в этот момент запустить malloc(5m) из dll - имеем ошибку выделения памяти, т.к. свободной памяти нет.

leaderПример.
Выделено Xmx10m
Запустился java-код, поработал, съел все доступные 10m
Допустим, из этих 10ти "живых" объектов - только 2m, остальное мусор, ждущий уборки gc'ом
Если в этот момент запустить malloc(5m) из dll - имеем ошибку выделения памяти, т.к. свободной памяти нет.

Если перед вызовом dll принудительно вызвать System.gc() - мусор уберется, останется 2m
Вызов malloc(5m) из dll отработает ОК

Blazkowiczleader,
Вы подрываете моё понимание работы JVM.

Сам в шоке.

BlazkowiczКакая-то конкретная ошибка вылетает при malloc или процесс крашится?
malloc используюет память вне Java кучи. Выходит если мусор собран то malloc приводит к сокращению размера Java кучи? GC log или jvisualvm поддтверждают?

Пример утрированый, в реальности все немного сложнее чем я описал.
Так что возможно твое понимание работы JVM правильное, а мое неправильное :)
Dll пропиетарная, используется для асинхронного обмена с неким сервером
Фактически там функции: 1. вызов команды (SendCommand) и 2. регистрация callback'а обработки результата (SetCallback) 3. Освобождение памяти , переданой в callback(FreeMemory).
Т.е. мы разово регистрируем колбэк, потом только шлем команды посредством SendCommand, принимем в колбэк результаты, работаем ним, затем освобождаем через FreeMemory. Callback-функция реализована на java.

Когда начинается интенсивный обмен (используемая jvm память начинает расти) и мы упраемся в установленный Xmx, то callback просто перестает вызываться.
Процесс не падает, ошибок нет, при этом сам обмен с сервером идет, ответ с сервера приходит, но не обрабатывается и в callback не передается.
Опытным путем выяснено, что если принудительно вставить System.gc() перед вызовом SendCommand, либо в самом callback'е, то в этой части все работает отлично. Побочный эффект от этого - высокая нагрузка на CPU из-за постоянной сборки мусора.
Т.е. момент сбоя точно кореллирует с достижением максимума использованной памяти.

GC log для Xmx1024 при отсутствии явного System.gc()

[GC (Allocation Failure) [PSYoungGen: 33280K->1104K(38400K)] 33280K->1184K(125952K), 0.0179369 secs] [Times: user=0.01 sys=0.00, real=0.03 secs]
[GC (Allocation Failure) [PSYoungGen: 34384K->992K(38400K)] 34464K->1080K(125952K), 0.0023701 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 34272K->1008K(38400K)] 34360K->1104K(125952K), 0.0022578 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 34288K->992K(38400K)] 34384K->1096K(125952K), 0.0274010 secs] [Times: user=0.05 sys=0.00, real=0.03 secs]
[GC (Allocation Failure) [PSYoungGen: 34272K->976K(38400K)] 34376K->1088K(125952K), 0.0013577 secs] [Times: user=0.08 sys=0.00, real=0.02 secs]
[GC (Allocation Failure) [PSYoungGen: 34256K->976K(68096K)] 34368K->1096K(155648K), 0.0024253 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 67536K->64K(68096K)] 67656K->1124K(155648K), 0.0025449 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 66624K->32K(134656K)] 67684K->1108K(222208K), 0.0119151 secs] [Times: user=0.00 sys=0.00, real=0.02 secs]
[GC (Allocation Failure) [PSYoungGen: 133152K->0K(134656K)] 134228K->1084K(222208K), 0.0014564 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 133120K->0K(214528K)] 134204K->1084K(302080K), 0.0003933 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 212992K->0K(214528K)] 214076K->1084K(302080K), 0.0011893 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 212992K->0K(342016K)] 214076K->1084K(429568K), 0.0109981 secs] [Times: user=0.05 sys=0.00, real=0.02 secs]
[GC (Allocation Failure) [PSYoungGen: 340992K->0K(342528K)] 342076K->1084K(430080K), 0.0008138 secs] [Times: user=0.00 sys=0.00, real=0.02 secs]
[GC (Allocation Failure) [PSYoungGen: 340992K->0K(347648K)] 342076K->1084K(435200K), 0.0007024 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 346112K->0K(347648K)] 347196K->1084K(435200K), 0.0008446 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 346112K->0K(347648K)] 347196K->1084K(435200K), 0.0008047 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 346112K->0K(347648K)] 347196K->1084K(435200K), 0.0007806 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 346112K->0K(347648K)] 347196K->1084K(435200K), 0.0006167 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 346112K->0K(347648K)] 347196K->1084K(435200K), 0.0007386 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 346112K->0K(347648K)] 347196K->1084K(435200K), 0.0185014 secs] [Times: user=0.05 sys=0.00, real=0.02 secs]
[GC (Allocation Failure) [PSYoungGen: 346112K->0K(347648K)] 347196K->1084K(435200K), 0.0007456 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 346112K->0K(347648K)] 347196K->1084K(435200K), 0.0285227 secs] [Times: user=0.03 sys=0.00, real=0.03 secs]
[GC (Allocation Failure) [PSYoungGen: 346112K->0K(346624K)] 347196K->1084K(434176K), 0.0114943 secs] [Times: user=0.00 sys=0.00, real=0.01 secs]
[GC (Allocation Failure) [PSYoungGen: 346112K->0K(347136K)] 347196K->1084K(434688K), 0.0133860 secs] [Times: user=0.05 sys=0.00, real=0.02 secs]
[GC (Allocation Failure) [PSYoungGen: 346112K->0K(347136K)] 347196K->1084K(434688K), 0.0010556 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 346112K->0K(348160K)] 347196K->1084K(435712K), 0.0160302 secs] [Times: user=0.05 sys=0.02, real=0.03 secs]
[GC (Allocation Failure) [PSYoungGen: 347136K->0K(348160K)] 348220K->1084K(435712K), 0.0003698 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 347136K->0K(348160K)] 348220K->1084K(435712K), 0.0065718 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 347136K->0K(348160K)] 348220K->1084K(435712K), 0.0008986 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 347136K->0K(348160K)] 348220K->1084K(435712K), 0.0005898 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 347136K->0K(348160K)] 348220K->1084K(435712K), 0.0007981 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 347136K->0K(348160K)] 348220K->1084K(435712K), 0.0009454 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 347136K->0K(348160K)] 348220K->1084K(435712K), 0.0007323 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 347136K->0K(347648K)] 348220K->1084K(435200K), 0.0007773 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 347136K->0K(348160K)] 348220K->1084K(435712K), 0.0317307 secs] [Times: user=0.03 sys=0.00, real=0.03 secs]
[GC (Allocation Failure) [PSYoungGen: 347648K->0K(348672K)] 348732K->1084K(436224K), 0.0004987 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0004120 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0008183 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0006644 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0008449 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0007483 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0005798 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0007323 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0008521 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0006921 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0006758 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0009188 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0006810 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0202077 secs] [Times: user=0.03 sys=0.00, real=0.03 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0156112 secs] [Times: user=0.00 sys=0.00, real=0.01 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0130872 secs] [Times: user=0.00 sys=0.00, real=0.02 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0004793 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0125291 secs] [Times: user=0.00 sys=0.00, real=0.02 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0132124 secs] [Times: user=0.00 sys=0.00, real=0.02 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0005922 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0008437 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0006360 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0008877 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0007057 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0007501 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0007688 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0045956 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0005011 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349244K->1084K(436224K), 0.0009092 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->64K(348672K)] 349244K->1148K(436224K), 0.0008705 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348224K->0K(348672K)] 349308K->1092K(436224K), 0.0006484 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0008823 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0006686 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0008479 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0007851 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0008117 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0005602 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0115963 secs] [Times: user=0.05 sys=0.00, real=0.02 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0280452 secs] [Times: user=0.05 sys=0.00, real=0.03 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0448451 secs] [Times: user=0.09 sys=0.00, real=0.05 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0074134 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0102903 secs] [Times: user=0.00 sys=0.00, real=0.02 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0004356 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0005011 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0003622 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0007507 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0006100 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0010752 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0006405 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0009318 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0008621 secs] [Times: user=0.00 sys=0.00, real=0.02 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0008932 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0021630 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0006191 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (Allocation Failure) [PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0007362 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
Здесь callback перестал вызываться

Heap
PSYoungGen total 348672K, used 303333K [0x00000000eab00000, 0x0000000100000000, 0x0000000100000000)
eden space 348160K, 87% used [0x00000000eab00000,0x00000000fd339710,0x00000000fff00000)
from space 512K, 0% used [0x00000000fff80000,0x00000000fff80000,0x0000000100000000)
to space 512K, 0% used [0x00000000fff00000,0x00000000fff00000,0x00000000fff80000)
ParOldGen total 87552K, used 1092K [0x00000000c0000000, 0x00000000c5580000, 0x00000000eab00000)
object space 87552K, 1% used [0x00000000c0000000,0x00000000c0111080,0x00000000c5580000)
Metaspace used 6216K, capacity 6356K, committed 6400K, reserved 1056768K
class space used 693K, capacity 754K, committed 768K, reserved 1048576K


При включенном принудительном System.gc() выглядит так:

[GC (System.gc()) [PSYoungGen: 887K->128K(38400K)] 1933K->1173K(125952K), 0.0018693 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[Full GC (System.gc()) [PSYoungGen: 128K->0K(38400K)] [ParOldGen: 1045K->1040K(87552K)] 1173K->1040K(125952K), [Metaspace: 5873K->5873K(1056768K)], 0.0085984 secs] [Times: user=0.06 sys=0.00, real=0.02 secs]
[GC (System.gc()) [PSYoungGen: 998K->226K(38400K)] 2039K->1274K(125952K), 0.0004920 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[Full GC (System.gc()) [PSYoungGen: 226K->0K(38400K)] [ParOldGen: 1048K->1080K(87552K)] 1274K->1080K(125952K), [Metaspace: 5931K->5931K(1056768K)], 0.0082546 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (System.gc()) [PSYoungGen: 998K->161K(38400K)] 2079K->1250K(125952K), 0.0007610 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[Full GC (System.gc()) [PSYoungGen: 161K->0K(38400K)] [ParOldGen: 1088K->1147K(87552K)] 1250K->1147K(125952K), [Metaspace: 6014K->6014K(1056768K)], 0.0058628 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (System.gc()) [PSYoungGen: 333K->128K(38400K)] 1480K->1275K(125952K), 0.0042512 secs] [Times: user=0.06 sys=0.00, real=0.02 secs]
[Full GC (System.gc()) [PSYoungGen: 128K->0K(38400K)] [ParOldGen: 1147K->1066K(87552K)] 1275K->1066K(125952K), [Metaspace: 6025K->6025K(1056768K)], 0.0055857 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (System.gc()) [PSYoungGen: 333K->96K(38400K)] 1399K->1162K(125952K), 0.0004519 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[Full GC (System.gc()) [PSYoungGen: 96K->0K(38400K)] [ParOldGen: 1066K->1065K(87552K)] 1162K->1065K(125952K), [Metaspace: 6025K->6025K(1056768K)], 0.0035129 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (System.gc()) [PSYoungGen: 333K->96K(38400K)] 1398K->1161K(125952K), 0.0098927 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[Full GC (System.gc()) [PSYoungGen: 96K->0K(38400K)] [ParOldGen: 1065K->1071K(87552K)] 1161K->1071K(125952K), [Metaspace: 6101K->6101K(1056768K)], 0.0066077 secs] [Times: user=0.06 sys=0.00, real=0.02 secs]
[GC (System.gc()) [PSYoungGen: 665K->64K(38400K)] 1737K->1135K(125952K), 0.0004718 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[Full GC (System.gc()) [PSYoungGen: 64K->0K(38400K)] [ParOldGen: 1071K->1071K(87552K)] 1135K->1071K(125952K), [Metaspace: 6103K->6103K(1056768K)], 0.0035186 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (System.gc()) [PSYoungGen: 665K->132K(38400K)] 1737K->1203K(125952K), 0.0010320 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[Full GC (System.gc()) [PSYoungGen: 132K->0K(38400K)] [ParOldGen: 1071K->1090K(87552K)] 1203K->1090K(125952K), [Metaspace: 6116K->6116K(1056768K)], 0.0111581 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (System.gc()) [PSYoungGen: 1997K->189K(38400K)] 3087K->1280K(125952K), 0.0010528 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[Full GC (System.gc()) [PSYoungGen: 189K->0K(38400K)] [ParOldGen: 1090K->1150K(87552K)] 1280K->1150K(125952K), [Metaspace: 6124K->6124K(1056768K)], 0.0061483 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (System.gc()) [PSYoungGen: 1664K->98K(38400K)] 2814K->1256K(125952K), 0.0045102 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[Full GC (System.gc()) [PSYoungGen: 98K->0K(38400K)] [ParOldGen: 1158K->1094K(87552K)] 1256K->1094K(125952K), [Metaspace: 6142K->6142K(1056768K)], 0.0064499 secs] [Times: user=0.06 sys=0.00, real=0.00 secs]
[GC (System.gc()) [PSYoungGen: 333K->125K(38400K)] 1427K->1220K(125952K), 0.0075196 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[Full GC (System.gc()) [PSYoungGen: 125K->0K(38400K)] [ParOldGen: 1094K->1154K(87552K)] 1220K->1154K(125952K), [Metaspace: 6144K->6144K(1056768K)], 0.0061030 secs] [Times: user=0.02 sys=0.00, real=0.03 secs]
[GC (System.gc()) [PSYoungGen: 333K->32K(38400K)] 1487K->1186K(125952K), 0.0057420 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[Full GC (System.gc()) [PSYoungGen: 32K->0K(38400K)] [ParOldGen: 1154K->1077K(87552K)] 1186K->1077K(125952K), [Metaspace: 6144K->6144K(1056768K)], 0.0065042 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (System.gc()) [PSYoungGen: 333K->32K(38400K)] 1410K->1109K(125952K), 0.0049373 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[Full GC (System.gc()) [PSYoungGen: 32K->0K(38400K)] [ParOldGen: 1077K->1077K(87552K)] 1109K->1077K(125952K), [Metaspace: 6144K->6144K(1056768K)], 0.0042642 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (System.gc()) [PSYoungGen: 1331K->96K(38400K)] 2409K->1181K(125952K), 0.0006481 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[Full GC (System.gc()) [PSYoungGen: 96K->0K(38400K)] [ParOldGen: 1085K->1078K(87552K)] 1181K->1078K(125952K), [Metaspace: 6149K->6149K(1056768K)], 0.0041413 secs] [Times: user=0.08 sys=0.00, real=0.02 secs]
[GC (System.gc()) [PSYoungGen: 333K->146K(38400K)] 1412K->1224K(125952K), 0.0014839 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[Full GC (System.gc()) [PSYoungGen: 146K->0K(38400K)] [ParOldGen: 1078K->1095K(87552K)] 1224K->1095K(125952K), [Metaspace: 6149K->6149K(1056768K)], 0.0118188 secs] [Times: user=0.02 sys=0.00, real=0.02 secs]
[GC (System.gc()) [PSYoungGen: 1331K->224K(38400K)] 2427K->1328K(125952K), 0.0014984 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[Full GC (System.gc()) [PSYoungGen: 224K->0K(38400K)] [ParOldGen: 1103K->1160K(87552K)] 1328K->1160K(125952K), [Metaspace: 6151K->6151K(1056768K)], 0.0061655 secs] [Times: user=0.01 sys=0.00, real=0.02 secs]
[GC (System.gc()) [PSYoungGen: 333K->96K(38400K)] 1494K->1256K(125952K), 0.0019696 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[Full GC (System.gc()) [PSYoungGen: 96K->0K(38400K)] [ParOldGen: 1160K->1079K(87552K)] 1256K->1079K(125952K), [Metaspace: 6151K->6151K(1056768K)], 0.0042841 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (System.gc()) [PSYoungGen: 665K->96K(38400K)] 1745K->1175K(125952K), 0.0012729 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[Full GC (System.gc()) [PSYoungGen: 96K->0K(38400K)] [ParOldGen: 1079K->1079K(87552K)] 1175K->1079K(125952K), [Metaspace: 6151K->6151K(1056768K)], 0.0074429 secs] [Times: user=0.03 sys=0.00, real=0.01 secs]
[GC (System.gc()) [PSYoungGen: 665K->160K(38400K)] 1745K->1239K(125952K), 0.0005512 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[Full GC (System.gc()) [PSYoungGen: 160K->0K(38400K)] [ParOldGen: 1079K->1079K(87552K)] 1239K->1079K(125952K), [Metaspace: 6152K->6152K(1056768K)], 0.0044384 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (System.gc()) [PSYoungGen: 665K->150K(38400K)] 1745K->1229K(125952K), 0.0009080 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[Full GC (System.gc()) [PSYoungGen: 150K->0K(38400K)] [ParOldGen: 1079K->1103K(87552K)] 1229K->1103K(125952K), [Metaspace: 6161K->6161K(1056768K)], 0.0294243 secs] [Times: user=0.08 sys=0.00, real=0.03 secs]
[GC (System.gc()) [PSYoungGen: 1331K->170K(38400K)] 2434K->1281K(125952K), 0.0024006 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[Full GC (System.gc()) [PSYoungGen: 170K->0K(38400K)] [ParOldGen: 1111K->1153K(87552K)] 1281K->1153K(125952K), [Metaspace: 6163K->6163K(1056768K)], 0.0070345 secs] [Times: user=0.01 sys=0.00, real=0.02 secs]
[GC (System.gc()) [PSYoungGen: 665K->96K(38400K)] 1819K->1249K(125952K), 0.0083301 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[Full GC (System.gc()) [PSYoungGen: 96K->0K(38400K)] [ParOldGen: 1153K->1080K(87552K)] 1249K->1080K(125952K), [Metaspace: 6163K->6163K(1056768K)], 0.0073328 secs] [Times: user=0.02 sys=0.00, real=0.02 secs]
[GC (System.gc()) [PSYoungGen: 1331K->64K(38400K)] 2412K->1144K(125952K), 0.0004383 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[Full GC (System.gc()) [PSYoungGen: 64K->0K(38400K)] [ParOldGen: 1080K->1080K(87552K)] 1144K->1080K(125952K), [Metaspace: 6163K->6163K(1056768K)], 0.0043692 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (System.gc()) [PSYoungGen: 333K->96K(38400K)] 1414K->1176K(125952K), 0.0034661 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[Full GC (System.gc()) [PSYoungGen: 96K->0K(38400K)] [ParOldGen: 1080K->1080K(87552K)] 1176K->1080K(125952K), [Metaspace: 6163K->6163K(1056768K)], 0.0044537 secs] [Times: user=0.02 sys=0.00, real=0.00 secs]
[GC (System.gc()) [PSYoungGen: 665K->32K(38400K)] 1746K->1112K(125952K), 0.0033082 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[Full GC (System.gc()) [PSYoungGen: 32K->0K(38400K)] [ParOldGen: 1080K->1081K(87552K)] 1112K->1081K(125952K), [Metaspace: 6164K->6164K(1056768K)], 0.0047040 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (System.gc()) [PSYoungGen: 665K->128K(38400K)] 1747K->1209K(125952K), 0.0004591 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[Full GC (System.gc()) [PSYoungGen: 128K->0K(38400K)] [ParOldGen: 1081K->1081K(87552K)] 1209K->1081K(125952K), [Metaspace: 6164K->6164K(1056768K)], 0.0044196 secs] [Times: user=0.01 sys=0.00, real=0.00 secs]
[GC (System.gc()) [PSYoungGen: 1331K->101K(38400K)] 2412K->1182K(125952K), 0.0071121 secs] [Times: user=0.08 sys=0.00, real=0.01 secs]
[Full GC (System.gc()) [PSYoungGen: 101K->0K(38400K)] [ParOldGen: 1081K->1102K(87552K)] 1182K->1102K(125952K), [Metaspace: 6164K->6164K(1056768K)], 0.0072920 secs] [Times: user=0.00 sys=0.00, real=0.03 secs]
[GC (System.gc()) [PSYoungGen: 665K->156K(38400K)] 1768K->1259K(125952K), 0.0241598 secs] [Times: user=0.11 sys=0.00, real=0.03 secs]
[Full GC (System.gc()) [PSYoungGen: 156K->0K(38400K)] [ParOldGen: 1102K->1156K(87552K)] 1259K->1156K(125952K), [Metaspace: 6166K->6166K(1056768K)], 0.0043197 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (System.gc()) [PSYoungGen: 665K->64K(38400K)] 1822K->1220K(125952K), 0.0006155 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[Full GC (System.gc()) [PSYoungGen: 64K->0K(38400K)] [ParOldGen: 1156K->1081K(87552K)] 1220K->1081K(125952K), [Metaspace: 6167K->6167K(1056768K)], 0.0053309 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (System.gc()) [PSYoungGen: 665K->155K(38400K)] 1747K->1237K(125952K), 0.0024359 secs] [Times: user=0.11 sys=0.00, real=0.02 secs]
[Full GC (System.gc()) [PSYoungGen: 155K->0K(38400K)] [ParOldGen: 1081K->1112K(87552K)] 1237K->1112K(125952K), [Metaspace: 6168K->6168K(1056768K)], 0.0048000 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
[GC (System.gc()) [PSYoungGen: 665K->176K(38400K)] 1778K->1289K(125952K), 0.0235730 secs] [Times: user=0.13 sys=0.00, real=0.03 secs]
И так далее..

забыл никОчень странный лог.

1) Почему не происходит проумошен после стольких минорных коллекций, не стоит ли у вас чтото вроде NeverTenure?
2) Почему не происходит Full GC автоматически после аллокейшен файлуре - загадко
3) Места в old gen ну просто завались

А не пробовали вы запускать с клбючиком XX:+PrintJNIGCStalls? И вообще сбросьте параметры JVM

1. не стоит
2. согласен
3. вижу

java 1.8.25 64-bit

В самих флагах нет ничего необычного
-XX:InitialHeapSize=132168832 -XX:MaxHeapSize=1073741824 -XX:+PrintCommandLineFlags -XX:+PrintGCDetails -XX:+PrintGCTimeStamps -XX:+PrintJNIGCStalls -XX:+PrintPromotionFailure -XX:+PrintReferenceGC -XX:+PrintTenuringDistribution -XX:+UseCompressedClassPointers -XX:+UseCompressedOops -XX:-UseLargePagesIndividualAllocation -XX:+UseParallelGC


Вот лог
5.003: [GC (Allocation Failure) 5.019: [SoftReference, 0 refs, 0.0000332 secs]5.019: [WeakReference, 41 refs, 0.0000091 secs]5.019: [FinalReference, 201 refs, 0.0002207 secs]5.019: [PhantomReference, 1 refs, 0.0000151 secs]5.019: [JNI Weak Reference, 0.0000112 secs]
Desired survivor size 5242880 bytes, new threshold 7 (max 15)
[PSYoungGen: 33280K->1407K(38400K)] 33280K->1479K(125952K), 0.0172472 secs] [Times: user=0.11 sys=0.00, real=0.03 secs]
6.310: [GC (Allocation Failure) 6.330: [SoftReference, 0 refs, 0.0000275 secs]6.330: [WeakReference, 37 refs, 0.0000293 secs]6.330: [FinalReference, 18 refs, 0.0000163 secs]6.330: [PhantomReference, 1 refs, 0.0000094 secs]6.330: [JNI Weak Reference, 0.0000066 secs]
Desired survivor size 5242880 bytes, new threshold 7 (max 15)
[PSYoungGen: 34687K->992K(38400K)] 34759K->1072K(125952K), 0.0204670 secs] [Times: user=0.05 sys=0.00, real=0.02 secs]
6.864: [GC (Allocation Failure) 6.877: [SoftReference, 0 refs, 0.0000275 secs]6.877: [WeakReference, 37 refs, 0.0000081 secs]6.877: [FinalReference, 18 refs, 0.0000054 secs]6.877: [PhantomReference, 1 refs, 0.0000383 secs]6.877: [JNI Weak Reference, 0.0000066 secs]
Desired survivor size 5242880 bytes, new threshold 7 (max 15)
[PSYoungGen: 34272K->992K(38400K)] 34352K->1080K(125952K), 0.0138988 secs] [Times: user=0.00 sys=0.00, real=0.01 secs]
7.215: [GC (Allocation Failure) 7.217: [SoftReference, 0 refs, 0.0000314 secs]7.217: [WeakReference, 37 refs, 0.0000130 secs]7.217: [FinalReference, 18 refs, 0.0000115 secs]7.217: [PhantomReference, 1 refs, 0.0000570 secs]7.217: [JNI Weak Reference, 0.0000172 secs]
Desired survivor size 5242880 bytes, new threshold 7 (max 15)
[PSYoungGen: 34272K->1056K(71680K)] 34360K->1152K(159232K), 0.0026448 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
7.528: [GC (Allocation Failure) 7.535: [SoftReference, 0 refs, 0.0000317 secs]7.535: [WeakReference, 37 refs, 0.0000142 secs]7.535: [FinalReference, 18 refs, 0.0000088 secs]7.535: [PhantomReference, 1 refs, 0.0000066 secs]7.535: [JNI Weak Reference, 0.0000078 secs]
Desired survivor size 1572864 bytes, new threshold 6 (max 15)
[PSYoungGen: 67616K->992K(71680K)] 67712K->1096K(159232K), 0.0074897 secs] [Times: user=0.05 sys=0.00, real=0.00 secs]
7.789: [GC (Allocation Failure) 7.791: [SoftReference, 0 refs, 0.0000284 secs]7.791: [WeakReference, 37 refs, 0.0000127 secs]7.791: [FinalReference, 18 refs, 0.0000100 secs]7.791: [PhantomReference, 1 refs, 0.0000420 secs]7.791: [JNI Weak Reference, 0.0000133 secs]
Desired survivor size 1572864 bytes, new threshold 5 (max 15)
[PSYoungGen: 67552K->992K(134656K)] 67656K->1104K(222208K), 0.0026535 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
8.025: [GC (Allocation Failure) 8.027: [SoftReference, 0 refs, 0.0000208 secs]8.027: [WeakReference, 0 refs, 0.0000088 secs]8.027: [FinalReference, 0 refs, 0.0000072 secs]8.027: [PhantomReference, 0 refs, 0.0000078 secs]8.027: [JNI Weak Reference, 0.0000371 secs]
Desired survivor size 2097152 bytes, new threshold 4 (max 15)
[PSYoungGen: 134112K->64K(134656K)] 134224K->1132K(222208K), 0.0023897 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
8.152: [GC (Allocation Failure) 8.160: [SoftReference, 0 refs, 0.0000356 secs]8.160: [WeakReference, 0 refs, 0.0000121 secs]8.160: [FinalReference, 0 refs, 0.0000211 secs]8.160: [PhantomReference, 0 refs, 0.0000106 secs]8.160: [JNI Weak Reference, 0.0000151 secs]
Desired survivor size 2097152 bytes, new threshold 3 (max 15)
[PSYoungGen: 133184K->64K(268288K)] 134252K->1140K(355840K), 0.0085691 secs] [Times: user=0.05 sys=0.00, real=0.02 secs]
8.208: [GC (Allocation Failure) 8.208: [SoftReference, 0 refs, 0.0000142 secs]8.209: [WeakReference, 0 refs, 0.0000051 secs]8.209: [FinalReference, 0 refs, 0.0000045 secs]8.209: [PhantomReference, 0 refs, 0.0000057 secs]8.209: [JNI Weak Reference, 0.0000042 secs]
Desired survivor size 2097152 bytes, new threshold 2 (max 15)
[PSYoungGen: 266304K->0K(268288K)] 267380K->1092K(355840K), 0.0006973 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
8.338: [GC (Allocation Failure) 8.338: [SoftReference, 0 refs, 0.0000326 secs]8.338: [WeakReference, 0 refs, 0.0000202 secs]8.338: [FinalReference, 0 refs, 0.0000712 secs]8.339: [PhantomReference, 0 refs, 0.0000118 secs]8.339: [JNI Weak Reference, 0.0000094 secs]
Desired survivor size 2097152 bytes, new threshold 1 (max 15)
[PSYoungGen: 266240K->0K(347136K)] 267332K->1092K(434688K), 0.0010202 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
8.577: [GC (Allocation Failure) 8.578: [SoftReference, 0 refs, 0.0000281 secs]8.578: [WeakReference, 0 refs, 0.0000106 secs]8.578: [FinalReference, 0 refs, 0.0000106 secs]8.578: [PhantomReference, 0 refs, 0.0000091 secs]8.578: [JNI Weak Reference, 0.0000088 secs]
Desired survivor size 2097152 bytes, new threshold 1 (max 15)
[PSYoungGen: 345088K->0K(347136K)] 346180K->1092K(434688K), 0.0015862 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
8.701: [GC (Allocation Failure) 8.701: [SoftReference, 0 refs, 0.0000314 secs]8.701: [WeakReference, 0 refs, 0.0000115 secs]8.701: [FinalReference, 0 refs, 0.0000112 secs]8.701: [PhantomReference, 0 refs, 0.0000435 secs]8.701: [JNI Weak Reference, 0.0000148 secs]
Desired survivor size 2097152 bytes, new threshold 1 (max 15)
[PSYoungGen: 345088K->0K(347136K)] 346180K->1092K(434688K), 0.0008578 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
8.760: [GC (Allocation Failure) 8.761: [SoftReference, 0 refs, 0.0000308 secs]8.761: [WeakReference, 0 refs, 0.0000115 secs]8.761: [FinalReference, 0 refs, 0.0000103 secs]8.761: [PhantomReference, 0 refs, 0.0000519 secs]8.761: [JNI Weak Reference, 0.0000112 secs]
Desired survivor size 2097152 bytes, new threshold 1 (max 15)
[PSYoungGen: 345088K->0K(347136K)] 346180K->1092K(434688K), 0.0008880 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
8.766: [GC (Allocation Failure) 8.767: [SoftReference, 0 refs, 0.0000311 secs]8.767: [WeakReference, 0 refs, 0.0000106 secs]8.767: [FinalReference, 0 refs, 0.0000103 secs]8.767: [PhantomReference, 0 refs, 0.0000211 secs]8.767: [JNI Weak Reference, 0.0000112 secs]
Desired survivor size 2097152 bytes, new threshold 1 (max 15)
[PSYoungGen: 345088K->0K(347136K)] 346180K->1092K(434688K), 0.0009514 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
8.828: [GC (Allocation Failure) 8.832: [SoftReference, 0 refs, 0.0000205 secs]8.832: [WeakReference, 0 refs, 0.0000124 secs]8.832: [FinalReference, 0 refs, 0.0000103 secs]8.832: [PhantomReference, 0 refs, 0.0000145 secs]8.832: [JNI Weak Reference, 0.0000097 secs]
Desired survivor size 2097152 bytes, new threshold 1 (max 15)
[PSYoungGen: 345088K->0K(347136K)] 346180K->1092K(434688K), 0.0047936 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
8.878: [GC (Allocation Failure) 8.879: [SoftReference, 0 refs, 0.0000251 secs]8.879: [WeakReference, 0 refs, 0.0000139 secs]8.879: [FinalReference, 0 refs, 0.0000048 secs]8.879: [PhantomReference, 0 refs, 0.0000912 secs]8.879: [JNI Weak Reference, 0.0000139 secs]
Desired survivor size 2097152 bytes, new threshold 1 (max 15)
[PSYoungGen: 345088K->0K(347136K)] 346180K->1092K(434688K), 0.0009372 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
8.882: [GC (Allocation Failure) 8.882: [SoftReference, 0 refs, 0.0000091 secs]8.882: [WeakReference, 0 refs, 0.0000051 secs]8.882: [FinalReference, 0 refs, 0.0000051 secs]8.882: [PhantomReference, 0 refs, 0.0000048 secs]8.882: [JNI Weak Reference, 0.0000042 secs]
Desired survivor size 1572864 bytes, new threshold 1 (max 15)
[PSYoungGen: 345088K->0K(345600K)] 346180K->1092K(433152K), 0.0008760 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
8.936: [GC (Allocation Failure) 8.937: [SoftReference, 0 refs, 0.0000293 secs]8.937: [WeakReference, 0 refs, 0.0000103 secs]8.937: [FinalReference, 0 refs, 0.0000094 secs]8.937: [PhantomReference, 0 refs, 0.0000118 secs]8.937: [JNI Weak Reference, 0.0000196 secs]
Desired survivor size 1572864 bytes, new threshold 1 (max 15)
[PSYoungGen: 345088K->0K(346624K)] 346180K->1092K(434176K), 0.0009638 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
9.014: [GC (Allocation Failure) 9.014: [SoftReference, 0 refs, 0.0000320 secs]9.014: [WeakReference, 0 refs, 0.0000106 secs]9.014: [FinalReference, 0 refs, 0.0000106 secs]9.014: [PhantomReference, 0 refs, 0.0000109 secs]9.014: [JNI Weak Reference, 0.0000094 secs]
Desired survivor size 1572864 bytes, new threshold 1 (max 15)
[PSYoungGen: 345088K->0K(346624K)] 346180K->1092K(434176K), 0.0010169 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
9.020: [GC (Allocation Failure) 9.022: [SoftReference, 0 refs, 0.0000278 secs]9.022: [WeakReference, 0 refs, 0.0000341 secs]9.022: [FinalReference, 0 refs, 0.0000148 secs]9.022: [PhantomReference, 0 refs, 0.0000136 secs]9.022: [JNI Weak Reference, 0.0000145 secs]
Desired survivor size 1572864 bytes, new threshold 1 (max 15)
[PSYoungGen: 345088K->0K(347648K)] 346180K->1092K(435200K), 0.0026789 secs] [Times: user=0.05 sys=0.00, real=0.02 secs]
9.071: [GC (Allocation Failure) 9.082: [SoftReference, 0 refs, 0.0000145 secs]9.083: [WeakReference, 0 refs, 0.0000051 secs]9.083: [FinalReference, 0 refs, 0.0000045 secs]9.083: [PhantomReference, 0 refs, 0.0000042 secs]9.083: [JNI Weak Reference, 0.0000057 secs]
Desired survivor size 1572864 bytes, new threshold 1 (max 15)
[PSYoungGen: 346112K->0K(347648K)] 347204K->1092K(435200K), 0.0113854 secs] [Times: user=0.00 sys=0.00, real=0.02 secs]
9.132: [GC (Allocation Failure) 9.145: [SoftReference, 0 refs, 0.0000586 secs]9.145: [WeakReference, 0 refs, 0.0000051 secs]9.145: [FinalReference, 0 refs, 0.0000042 secs]9.145: [PhantomReference, 0 refs, 0.0000042 secs]9.145: [JNI Weak Reference, 0.0000045 secs]
Desired survivor size 1572864 bytes, new threshold 1 (max 15)
[PSYoungGen: 346112K->0K(347648K)] 347204K->1092K(435200K), 0.0130579 secs] [Times: user=0.00 sys=0.00, real=0.02 secs]
9.148: [GC (Allocation Failure) 9.160: [SoftReference, 0 refs, 0.0000193 secs]9.160: [WeakReference, 0 refs, 0.0000127 secs]9.160: [FinalReference, 0 refs, 0.0000196 secs]9.160: [PhantomReference, 0 refs, 0.0000075 secs]9.161: [JNI Weak Reference, 0.0000199 secs]
Desired survivor size 1572864 bytes, new threshold 1 (max 15)
[PSYoungGen: 346112K->0K(347648K)] 347204K->1092K(435200K), 0.0132085 secs] [Times: user=0.05 sys=0.00, real=0.02 secs]
9.213: [GC (Allocation Failure) 9.223: [SoftReference, 0 refs, 0.0000184 secs]9.223: [WeakReference, 0 refs, 0.0000133 secs]9.223: [FinalReference, 0 refs, 0.0000196 secs]9.223: [PhantomReference, 0 refs, 0.0000091 secs]9.223: [JNI Weak Reference, 0.0000163 secs]
Desired survivor size 1572864 bytes, new threshold 1 (max 15)
[PSYoungGen: 346112K->0K(347648K)] 347204K->1092K(435200K), 0.0104771 secs] [Times: user=0.08 sys=0.00, real=0.02 secs]
9.273: [GC (Allocation Failure) 9.274: [SoftReference, 0 refs, 0.0000148 secs]9.274: [WeakReference, 0 refs, 0.0000072 secs]9.274: [FinalReference, 0 refs, 0.0000051 secs]9.274: [PhantomReference, 0 refs, 0.0000045 secs]9.274: [JNI Weak Reference, 0.0000045 secs]
Desired survivor size 1048576 bytes, new threshold 1 (max 15)
[PSYoungGen: 346112K->0K(346624K)] 347204K->1092K(434176K), 0.0006487 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
9.276: [GC (Allocation Failure) 9.285: [SoftReference, 0 refs, 0.0000154 secs]9.285: [WeakReference, 0 refs, 0.0000103 secs]9.285: [FinalReference, 0 refs, 0.0000063 secs]9.285: [PhantomReference, 0 refs, 0.0000063 secs]9.285: [JNI Weak Reference, 0.0000057 secs]
Desired survivor size 1048576 bytes, new threshold 1 (max 15)
[PSYoungGen: 346112K->0K(347136K)] 347204K->1092K(434688K), 0.0093210 secs] [Times: user=0.08 sys=0.00, real=0.02 secs]
9.359: [GC (Allocation Failure) 9.360: [SoftReference, 0 refs, 0.0000314 secs]9.360: [WeakReference, 0 refs, 0.0000112 secs]9.360: [FinalReference, 0 refs, 0.0000106 secs]9.360: [PhantomReference, 0 refs, 0.0000371 secs]9.360: [JNI Weak Reference, 0.0000163 secs]
Desired survivor size 1048576 bytes, new threshold 1 (max 15)
[PSYoungGen: 346112K->0K(347136K)] 347204K->1092K(434688K), 0.0010112 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
9.435: [GC (Allocation Failure) 9.435: [SoftReference, 0 refs, 0.0000314 secs]9.435: [WeakReference, 0 refs, 0.0000112 secs]9.435: [FinalReference, 0 refs, 0.0000100 secs]9.435: [PhantomReference, 0 refs, 0.0000097 secs]9.435: [JNI Weak Reference, 0.0000139 secs]
Desired survivor size 1048576 bytes, new threshold 1 (max 15)
[PSYoungGen: 346112K->0K(348160K)] 347204K->1092K(435712K), 0.0008841 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
9.441: [GC (Allocation Failure) 9.457: [SoftReference, 0 refs, 0.0000124 secs]9.457: [WeakReference, 0 refs, 0.0000048 secs]9.457: [FinalReference, 0 refs, 0.0000091 secs]9.457: [PhantomReference, 0 refs, 0.0000048 secs]9.457: [JNI Weak Reference, 0.0000039 secs]
Desired survivor size 1048576 bytes, new threshold 1 (max 15)
[PSYoungGen: 347136K->0K(348160K)] 348228K->1092K(435712K), 0.0163178 secs] [Times: user=0.16 sys=0.00, real=0.03 secs]
9.510: [GC (Allocation Failure) 9.510: [SoftReference, 0 refs, 0.0000220 secs]9.510: [WeakReference, 0 refs, 0.0000190 secs]9.510: [FinalReference, 0 refs, 0.0000048 secs]9.510: [PhantomReference, 0 refs, 0.0000181 secs]9.510: [JNI Weak Reference, 0.0000057 secs]
Desired survivor size 1048576 bytes, new threshold 1 (max 15)
[PSYoungGen: 347136K->0K(348160K)] 348228K->1092K(435712K), 0.0006357 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
9.592: [GC (Allocation Failure) 9.594: [SoftReference, 0 refs, 0.0000223 secs]9.594: [WeakReference, 0 refs, 0.0000100 secs]9.594: [FinalReference, 0 refs, 0.0000097 secs]9.594: [PhantomReference, 0 refs, 0.0000094 secs]9.594: [JNI Weak Reference, 0.0000136 secs]
Desired survivor size 1048576 bytes, new threshold 1 (max 15)
[PSYoungGen: 347136K->0K(348160K)] 348228K->1092K(435712K), 0.0024706 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
9.599: [GC (Allocation Failure) 9.600: [SoftReference, 0 refs, 0.0000278 secs]9.600: [WeakReference, 0 refs, 0.0000106 secs]9.600: [FinalReference, 0 refs, 0.0000106 secs]9.600: [PhantomReference, 0 refs, 0.0000232 secs]9.600: [JNI Weak Reference, 0.0000139 secs]
Desired survivor size 1048576 bytes, new threshold 1 (max 15)
[PSYoungGen: 347136K->0K(348160K)] 348228K->1092K(435712K), 0.0008874 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
9.667: [GC (Allocation Failure) 9.667: [SoftReference, 0 refs, 0.0000163 secs]9.667: [WeakReference, 0 refs, 0.0000088 secs]9.667: [FinalReference, 0 refs, 0.0000078 secs]9.667: [PhantomReference, 0 refs, 0.0000081 secs]9.667: [JNI Weak Reference, 0.0000051 secs]
Desired survivor size 1048576 bytes, new threshold 1 (max 15)
[PSYoungGen: 347136K->0K(348160K)] 348228K->1092K(435712K), 0.0005756 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
9.748: [GC (Allocation Failure) 9.763: [SoftReference, 0 refs, 0.0000202 secs]9.763: [WeakReference, 0 refs, 0.0000075 secs]9.763: [FinalReference, 0 refs, 0.0000066 secs]9.763: [PhantomReference, 0 refs, 0.0000081 secs]9.763: [JNI Weak Reference, 0.0000634 secs]
Desired survivor size 1048576 bytes, new threshold 1 (max 15)
[PSYoungGen: 347136K->0K(348160K)] 348228K->1092K(435712K), 0.0150797 secs] [Times: user=0.05 sys=0.00, real=0.01 secs]
9.766: [GC (Allocation Failure) 9.786: [SoftReference, 0 refs, 0.0000266 secs]9.786: [WeakReference, 0 refs, 0.0000051 secs]9.786: [FinalReference, 0 refs, 0.0000045 secs]9.786: [PhantomReference, 0 refs, 0.0000048 secs]9.786: [JNI Weak Reference, 0.0000045 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 347136K->0K(348160K)] 348228K->1092K(435712K), 0.0195346 secs] [Times: user=0.09 sys=0.00, real=0.03 secs]
9.822: [GC (Allocation Failure) 9.822: [SoftReference, 0 refs, 0.0000115 secs]9.822: [WeakReference, 0 refs, 0.0000075 secs]9.822: [FinalReference, 0 refs, 0.0000057 secs]9.822: [PhantomReference, 0 refs, 0.0000060 secs]9.822: [JNI Weak Reference, 0.0000048 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 347136K->0K(348160K)] 348228K->1092K(435712K), 0.0005726 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
9.904: [GC (Allocation Failure) 9.906: [SoftReference, 0 refs, 0.0000323 secs]9.906: [WeakReference, 0 refs, 0.0000106 secs]9.906: [FinalReference, 0 refs, 0.0000094 secs]9.906: [PhantomReference, 0 refs, 0.0000486 secs]9.906: [JNI Weak Reference, 0.0000151 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 347648K->0K(348160K)] 348740K->1092K(435712K), 0.0023475 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
9.911: [GC (Allocation Failure) 9.911: [SoftReference, 0 refs, 0.0000163 secs]9.911: [WeakReference, 0 refs, 0.0000072 secs]9.911: [FinalReference, 0 refs, 0.0000091 secs]9.911: [PhantomReference, 0 refs, 0.0000078 secs]9.911: [JNI Weak Reference, 0.0000060 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 347648K->0K(348672K)] 348740K->1092K(436224K), 0.0004006 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
9.984: [GC (Allocation Failure) 9.985: [SoftReference, 0 refs, 0.0000326 secs]9.985: [WeakReference, 0 refs, 0.0000109 secs]9.985: [FinalReference, 0 refs, 0.0000118 secs]9.985: [PhantomReference, 0 refs, 0.0000779 secs]9.985: [JNI Weak Reference, 0.0000157 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0010628 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
10.044: [GC (Allocation Failure) 10.044: [SoftReference, 0 refs, 0.0000335 secs]10.045: [WeakReference, 0 refs, 0.0000118 secs]10.045: [FinalReference, 0 refs, 0.0000103 secs]10.045: [PhantomReference, 0 refs, 0.0000226 secs]10.045: [JNI Weak Reference, 0.0000097 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0009858 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
10.050: [GC (Allocation Failure) 10.050: [SoftReference, 0 refs, 0.0000314 secs]10.050: [WeakReference, 0 refs, 0.0000112 secs]10.050: [FinalReference, 0 refs, 0.0000106 secs]10.050: [PhantomReference, 0 refs, 0.0000257 secs]10.050: [JNI Weak Reference, 0.0000112 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0009140 secs] [Times: user=0.02 sys=0.00, real=0.02 secs]
10.118: [GC (Allocation Failure) 10.118: [SoftReference, 0 refs, 0.0000356 secs]10.118: [WeakReference, 0 refs, 0.0000398 secs]10.119: [FinalReference, 0 refs, 0.0000115 secs]10.119: [PhantomReference, 0 refs, 0.0000220 secs]10.119: [JNI Weak Reference, 0.0000057 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0006755 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
10.200: [GC (Allocation Failure) 10.200: [SoftReference, 0 refs, 0.0000344 secs]10.200: [WeakReference, 0 refs, 0.0000115 secs]10.200: [FinalReference, 0 refs, 0.0000115 secs]10.200: [PhantomReference, 0 refs, 0.0000217 secs]10.200: [JNI Weak Reference, 0.0000241 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0010447 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
10.205: [GC (Allocation Failure) 10.206: [SoftReference, 0 refs, 0.0000314 secs]10.206: [WeakReference, 0 refs, 0.0000118 secs]10.206: [FinalReference, 0 refs, 0.0000112 secs]10.206: [PhantomReference, 0 refs, 0.0000338 secs]10.206: [JNI Weak Reference, 0.0000151 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0008068 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
10.262: [GC (Allocation Failure) 10.262: [SoftReference, 0 refs, 0.0000302 secs]10.262: [WeakReference, 0 refs, 0.0000106 secs]10.262: [FinalReference, 0 refs, 0.0000115 secs]10.262: [PhantomReference, 0 refs, 0.0000368 secs]10.262: [JNI Weak Reference, 0.0000121 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0009300 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
10.322: [GC (Allocation Failure) 10.334: [SoftReference, 0 refs, 0.0000290 secs]10.334: [WeakReference, 0 refs, 0.0000154 secs]10.334: [FinalReference, 0 refs, 0.0000163 secs]10.334: [PhantomReference, 0 refs, 0.0000151 secs]10.334: [JNI Weak Reference, 0.0000124 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0120881 secs] [Times: user=0.05 sys=0.00, real=0.02 secs]
10.340: [GC (Allocation Failure) 10.348: [SoftReference, 0 refs, 0.0000323 secs]10.348: [WeakReference, 0 refs, 0.0000115 secs]10.348: [FinalReference, 0 refs, 0.0000109 secs]10.348: [PhantomReference, 0 refs, 0.0000217 secs]10.348: [JNI Weak Reference, 0.0000139 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0091067 secs] [Times: user=0.05 sys=0.00, real=0.02 secs]
10.383: [GC (Allocation Failure) 10.394: [SoftReference, 0 refs, 0.0000148 secs]10.394: [WeakReference, 0 refs, 0.0000057 secs]10.394: [FinalReference, 0 refs, 0.0000045 secs]10.394: [PhantomReference, 0 refs, 0.0000060 secs]10.394: [JNI Weak Reference, 0.0000042 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0114611 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
10.440: [GC (Allocation Failure) 10.440: [SoftReference, 0 refs, 0.0000368 secs]10.440: [WeakReference, 0 refs, 0.0000229 secs]10.440: [FinalReference, 0 refs, 0.0000260 secs]10.441: [PhantomReference, 0 refs, 0.0000752 secs]10.441: [JNI Weak Reference, 0.0000112 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0006786 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
10.443: [GC (Allocation Failure) 10.443: [SoftReference, 0 refs, 0.0000202 secs]10.443: [WeakReference, 0 refs, 0.0000109 secs]10.443: [FinalReference, 0 refs, 0.0000100 secs]10.443: [PhantomReference, 0 refs, 0.0000100 secs]10.443: [JNI Weak Reference, 0.0000151 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0005023 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
10.491: [GC (Allocation Failure) 10.491: [SoftReference, 0 refs, 0.0000142 secs]10.491: [WeakReference, 0 refs, 0.0000048 secs]10.491: [FinalReference, 0 refs, 0.0000042 secs]10.491: [PhantomReference, 0 refs, 0.0000057 secs]10.491: [JNI Weak Reference, 0.0000045 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0010770 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
10.556: [GC (Allocation Failure) 10.556: [SoftReference, 0 refs, 0.0000293 secs]10.556: [WeakReference, 0 refs, 0.0000112 secs]10.556: [FinalReference, 0 refs, 0.0000190 secs]10.556: [PhantomReference, 0 refs, 0.0000127 secs]10.556: [JNI Weak Reference, 0.0000136 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0008352 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
10.561: [GC (Allocation Failure) 10.570: [SoftReference, 0 refs, 0.0000205 secs]10.570: [WeakReference, 0 refs, 0.0000072 secs]10.570: [FinalReference, 0 refs, 0.0000066 secs]10.570: [PhantomReference, 0 refs, 0.0000329 secs]10.570: [JNI Weak Reference, 0.0000078 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0088752 secs] [Times: user=0.06 sys=0.02, real=0.02 secs]
10.640: [GC (Allocation Failure) 10.640: [SoftReference, 0 refs, 0.0000244 secs]10.640: [WeakReference, 0 refs, 0.0000103 secs]10.640: [FinalReference, 0 refs, 0.0000094 secs]10.640: [PhantomReference, 0 refs, 0.0000100 secs]10.640: [JNI Weak Reference, 0.0000085 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0007208 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
10.694: [GC (Allocation Failure) 10.708: [SoftReference, 0 refs, 0.0000208 secs]10.708: [WeakReference, 0 refs, 0.0000054 secs]10.708: [FinalReference, 0 refs, 0.0000045 secs]10.708: [PhantomReference, 0 refs, 0.0000042 secs]10.708: [JNI Weak Reference, 0.0000048 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0141325 secs] [Times: user=0.05 sys=0.00, real=0.02 secs]
10.711: [GC (Allocation Failure) 10.723: [SoftReference, 0 refs, 0.0000145 secs]10.723: [WeakReference, 0 refs, 0.0000302 secs]10.724: [FinalReference, 0 refs, 0.0000088 secs]10.724: [PhantomReference, 0 refs, 0.0000069 secs]10.724: [JNI Weak Reference, 0.0000054 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0131557 secs] [Times: user=0.00 sys=0.00, real=0.02 secs]
10.795: [GC (Allocation Failure) 10.796: [SoftReference, 0 refs, 0.0000287 secs]10.796: [WeakReference, 0 refs, 0.0000106 secs]10.796: [FinalReference, 0 refs, 0.0000115 secs]10.796: [PhantomReference, 0 refs, 0.0000214 secs]10.796: [JNI Weak Reference, 0.0000091 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0008243 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
10.872: [GC (Allocation Failure) 10.872: [SoftReference, 0 refs, 0.0000323 secs]10.872: [WeakReference, 0 refs, 0.0000106 secs]10.872: [FinalReference, 0 refs, 0.0000109 secs]10.872: [PhantomReference, 0 refs, 0.0000809 secs]10.872: [JNI Weak Reference, 0.0000112 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0009846 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
10.877: [GC (Allocation Failure) 10.878: [SoftReference, 0 refs, 0.0000305 secs]10.878: [WeakReference, 0 refs, 0.0000106 secs]10.878: [FinalReference, 0 refs, 0.0000097 secs]10.878: [PhantomReference, 0 refs, 0.0000429 secs]10.878: [JNI Weak Reference, 0.0000100 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0009514 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
10.950: [GC (Allocation Failure) 10.979: [SoftReference, 0 refs, 0.0000241 secs]10.979: [WeakReference, 0 refs, 0.0000054 secs]10.979: [FinalReference, 0 refs, 0.0000048 secs]10.979: [PhantomReference, 0 refs, 0.0000048 secs]10.979: [JNI Weak Reference, 0.0000042 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0287102 secs] [Times: user=0.05 sys=0.00, real=0.03 secs]
11.005: [GC (Allocation Failure) 11.006: [SoftReference, 0 refs, 0.0000181 secs]11.006: [WeakReference, 0 refs, 0.0000326 secs]11.006: [FinalReference, 0 refs, 0.0000069 secs]11.006: [PhantomReference, 0 refs, 0.0000063 secs]11.006: [JNI Weak Reference, 0.0000060 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0011929 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
11.009: [GC (Allocation Failure) 11.035: [SoftReference, 0 refs, 0.0000392 secs]11.035: [WeakReference, 0 refs, 0.0000066 secs]11.035: [FinalReference, 0 refs, 0.0000115 secs]11.035: [PhantomReference, 0 refs, 0.0000081 secs]11.035: [JNI Weak Reference, 0.0000100 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0268782 secs] [Times: user=0.05 sys=0.00, real=0.03 secs]
11.073: [GC (Allocation Failure) 11.073: [SoftReference, 0 refs, 0.0000272 secs]11.073: [WeakReference, 0 refs, 0.0000109 secs]11.073: [FinalReference, 0 refs, 0.0000106 secs]11.073: [PhantomReference, 0 refs, 0.0000112 secs]11.073: [JNI Weak Reference, 0.0000112 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0008536 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
11.152: [GC (Allocation Failure) 11.153: [SoftReference, 0 refs, 0.0000299 secs]11.153: [WeakReference, 0 refs, 0.0000121 secs]11.153: [FinalReference, 0 refs, 0.0000100 secs]11.153: [PhantomReference, 0 refs, 0.0000112 secs]11.153: [JNI Weak Reference, 0.0000927 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0008989 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
11.158: [GC (Allocation Failure) 11.158: [SoftReference, 0 refs, 0.0000308 secs]11.158: [WeakReference, 0 refs, 0.0000106 secs]11.159: [FinalReference, 0 refs, 0.0000100 secs]11.159: [PhantomReference, 0 refs, 0.0000308 secs]11.159: [JNI Weak Reference, 0.0000157 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0007453 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
11.215: [GC (Allocation Failure) 11.215: [SoftReference, 0 refs, 0.0000317 secs]11.215: [WeakReference, 0 refs, 0.0000106 secs]11.215: [FinalReference, 0 refs, 0.0000106 secs]11.215: [PhantomReference, 0 refs, 0.0000791 secs]11.215: [JNI Weak Reference, 0.0000103 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0009668 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
11.273: [GC (Allocation Failure) 11.275: [SoftReference, 0 refs, 0.0000302 secs]11.275: [WeakReference, 0 refs, 0.0000202 secs]11.275: [FinalReference, 0 refs, 0.0000142 secs]11.275: [PhantomReference, 0 refs, 0.0000154 secs]11.275: [JNI Weak Reference, 0.0000091 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0026390 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
11.280: [GC (Allocation Failure) 11.282: [SoftReference, 0 refs, 0.0000281 secs]11.282: [WeakReference, 0 refs, 0.0000229 secs]11.282: [FinalReference, 0 refs, 0.0000178 secs]11.282: [PhantomReference, 0 refs, 0.0000148 secs]11.282: [JNI Weak Reference, 0.0000085 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0016076 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
11.336: [GC (Allocation Failure) 11.365: [SoftReference, 0 refs, 0.0000241 secs]11.365: [WeakReference, 0 refs, 0.0000051 secs]11.365: [FinalReference, 0 refs, 0.0000057 secs]11.365: [PhantomReference, 0 refs, 0.0000042 secs]11.365: [JNI Weak Reference, 0.0000051 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0294660 secs] [Times: user=0.05 sys=0.00, real=0.03 secs]
11.413: [GC (Allocation Failure) 11.414: [SoftReference, 0 refs, 0.0000163 secs]11.414: [WeakReference, 0 refs, 0.0000139 secs]11.414: [FinalReference, 0 refs, 0.0000139 secs]11.414: [PhantomReference, 0 refs, 0.0000045 secs]11.414: [JNI Weak Reference, 0.0000088 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0006915 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
11.416: [GC (Allocation Failure) 11.417: [SoftReference, 0 refs, 0.0000238 secs]11.417: [WeakReference, 0 refs, 0.0000097 secs]11.417: [FinalReference, 0 refs, 0.0000091 secs]11.417: [PhantomReference, 0 refs, 0.0000169 secs]11.417: [JNI Weak Reference, 0.0000036 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0005805 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
11.498: [GC (Allocation Failure) 11.498: [SoftReference, 0 refs, 0.0000320 secs]11.498: [WeakReference, 0 refs, 0.0000112 secs]11.498: [FinalReference, 0 refs, 0.0000214 secs]11.498: [PhantomReference, 0 refs, 0.0000127 secs]11.498: [JNI Weak Reference, 0.0000094 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0011597 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
11.552: [GC (Allocation Failure) 11.556: [SoftReference, 0 refs, 0.0000314 secs]11.556: [WeakReference, 0 refs, 0.0000103 secs]11.556: [FinalReference, 0 refs, 0.0000103 secs]11.556: [PhantomReference, 0 refs, 0.0000229 secs]11.556: [JNI Weak Reference, 0.0000127 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0043747 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
11.561: [GC (Allocation Failure) 11.562: [SoftReference, 0 refs, 0.0000308 secs]11.562: [WeakReference, 0 refs, 0.0000112 secs]11.562: [FinalReference, 0 refs, 0.0000202 secs]11.562: [PhantomReference, 0 refs, 0.0000136 secs]11.562: [JNI Weak Reference, 0.0000136 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0008533 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
11.638: [GC (Allocation Failure) 11.639: [SoftReference, 0 refs, 0.0000338 secs]11.639: [WeakReference, 0 refs, 0.0000112 secs]11.639: [FinalReference, 0 refs, 0.0000103 secs]11.639: [PhantomReference, 0 refs, 0.0000160 secs]11.639: [JNI Weak Reference, 0.0000151 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0009143 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
11.692: [GC (Allocation Failure) 11.694: [SoftReference, 0 refs, 0.0000519 secs]11.694: [WeakReference, 0 refs, 0.0000054 secs]11.694: [FinalReference, 0 refs, 0.0000048 secs]11.694: [PhantomReference, 0 refs, 0.0000166 secs]11.694: [JNI Weak Reference, 0.0000042 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0028938 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
11.697: [GC (Allocation Failure) 11.697: [SoftReference, 0 refs, 0.0000124 secs]11.697: [WeakReference, 0 refs, 0.0000045 secs]11.697: [FinalReference, 0 refs, 0.0000054 secs]11.697: [PhantomReference, 0 refs, 0.0000048 secs]11.697: [JNI Weak Reference, 0.0000048 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0005346 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
11.762: [GC (Allocation Failure) 11.764: [SoftReference, 0 refs, 0.0000229 secs]11.764: [WeakReference, 0 refs, 0.0000100 secs]11.764: [FinalReference, 0 refs, 0.0000103 secs]11.764: [PhantomReference, 0 refs, 0.0000622 secs]11.764: [JNI Weak Reference, 0.0000157 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0022451 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
11.769: [GC (Allocation Failure) 11.769: [SoftReference, 0 refs, 0.0000305 secs]11.769: [WeakReference, 0 refs, 0.0000187 secs]11.769: [FinalReference, 0 refs, 0.0000220 secs]11.769: [PhantomReference, 0 refs, 0.0000489 secs]11.770: [JNI Weak Reference, 0.0000085 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0011860 secs] [Times: user=0.02 sys=0.00, real=0.02 secs]
11.824: [GC (Allocation Failure) 11.831: [SoftReference, 0 refs, 0.0000347 secs]11.831: [WeakReference, 0 refs, 0.0000109 secs]11.831: [FinalReference, 0 refs, 0.0000097 secs]11.831: [PhantomReference, 0 refs, 0.0000097 secs]11.831: [JNI Weak Reference, 0.0000172 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0074764 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
11.904: [GC (Allocation Failure) 11.910: [SoftReference, 0 refs, 0.0000260 secs]11.910: [WeakReference, 0 refs, 0.0000103 secs]11.910: [FinalReference, 0 refs, 0.0000133 secs]11.910: [PhantomReference, 0 refs, 0.0000106 secs]11.910: [JNI Weak Reference, 0.0000124 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0065507 secs] [Times: user=0.05 sys=0.00, real=0.02 secs]
11.916: [GC (Allocation Failure) 11.926: [SoftReference, 0 refs, 0.0000314 secs]11.926: [WeakReference, 0 refs, 0.0000048 secs]11.926: [FinalReference, 0 refs, 0.0000091 secs]11.926: [PhantomReference, 0 refs, 0.0000196 secs]11.926: [JNI Weak Reference, 0.0000057 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0109585 secs] [Times: user=0.05 sys=0.00, real=0.02 secs]
11.978: [GC (Allocation Failure) 11.979: [SoftReference, 0 refs, 0.0000420 secs]11.979: [WeakReference, 0 refs, 0.0000054 secs]11.979: [FinalReference, 0 refs, 0.0000498 secs]11.979: [PhantomReference, 0 refs, 0.0000145 secs]11.979: [JNI Weak Reference, 0.0000045 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0008005 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
12.060: [GC (Allocation Failure) 12.082: [SoftReference, 0 refs, 0.0000281 secs]12.082: [WeakReference, 0 refs, 0.0000142 secs]12.082: [FinalReference, 0 refs, 0.0000142 secs]12.082: [PhantomReference, 0 refs, 0.0000471 secs]12.082: [JNI Weak Reference, 0.0000136 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0223551 secs] [Times: user=0.02 sys=0.00, real=0.03 secs]
12.084: [GC (Allocation Failure) 12.084: [SoftReference, 0 refs, 0.0000081 secs]12.084: [WeakReference, 0 refs, 0.0000057 secs]12.084: [FinalReference, 0 refs, 0.0000051 secs]12.084: [PhantomReference, 0 refs, 0.0000045 secs]12.084: [JNI Weak Reference, 0.0000039 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0004232 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
12.134: [GC (Allocation Failure) 12.138: [SoftReference, 0 refs, 0.0000154 secs]12.138: [WeakReference, 0 refs, 0.0000048 secs]12.138: [FinalReference, 0 refs, 0.0000045 secs]12.138: [PhantomReference, 0 refs, 0.0000054 secs]12.138: [JNI Weak Reference, 0.0000045 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0049011 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
12.217: [GC (Allocation Failure) 12.218: [SoftReference, 0 refs, 0.0000296 secs]12.218: [WeakReference, 0 refs, 0.0000103 secs]12.218: [FinalReference, 0 refs, 0.0000109 secs]12.218: [PhantomReference, 0 refs, 0.0000106 secs]12.218: [JNI Weak Reference, 0.0000085 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0014175 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]
12.223: [GC (Allocation Failure) 12.253: [SoftReference, 0 refs, 0.0000284 secs]12.253: [WeakReference, 0 refs, 0.0000051 secs]12.253: [FinalReference, 0 refs, 0.0000060 secs]12.253: [PhantomReference, 0 refs, 0.0000072 secs]12.253: [JNI Weak Reference, 0.0000051 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0307316 secs] [Times: user=0.05 sys=0.00, real=0.03 secs]
12.327: [GC (Allocation Failure) 12.328: [SoftReference, 0 refs, 0.0000302 secs]12.328: [WeakReference, 0 refs, 0.0000106 secs]12.328: [FinalReference, 0 refs, 0.0000097 secs]12.328: [PhantomReference, 0 refs, 0.0000356 secs]12.328: [JNI Weak Reference, 0.0000094 secs]
Desired survivor size 524288 bytes, new threshold 1 (max 15)
[PSYoungGen: 348160K->0K(348672K)] 349252K->1092K(436224K), 0.0010411 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]

забыл никвечером более внимательно посмотрю, пробовали запускать под другой jdk? 1.6 1.7

java-код вообще практически ничего не выделяет под себя, пара сотен килобайт.
когда пытался диагностировать проблему, отключал вообще всю обработку в java - ни к чему не привело
под 1.7 пробовал - то же самое
x86, x64 - то же самое
ставил +Xmx10G - то же самое, но чуть позже

new gen действительно пустой, сам java-код после остановки работы dll продолжает себе работать как ни в чем не бывало

dll точно рабочая, используется в нескольких других комм. продуктах (не java)

еще раз уточню, чтобы не было путаницы, - процесс не падает, ошибок нет, просто останавливается обработка
ситуацию спасает постоянный периодический System.gc()

BlazkowiczТак нужно идентифицировать конкретную точку, которая перестаёт работать. Какой-то callback из DLL в Java?

Да

Basil A. SidorovleaderВ самих флагах нет ничего необычногоОбильное использование -XX говорит или о глубоких знаниях или об интенсивном применении метода тыка. Начните с простого:

умники подтянулись, смотрю :)

Leonid KudryavtsevНафига JRE заставлять динамически менять конфигурацию памяти туда-обратно


Какую еще динамическую конфигурацию памяти? Чо за бред??