diff --git a/pom.xml b/pom.xml index 92001649..85ca5c2f 100644 --- a/pom.xml +++ b/pom.xml @@ -59,6 +59,10 @@ Michi Gysel + + Roland Illig + https://github.com/rillig + Johan Kaving diff --git a/src/main/java/com/tagtraum/perf/gcviewer/imp/DataReaderFactory.java b/src/main/java/com/tagtraum/perf/gcviewer/imp/DataReaderFactory.java index 8b419cb2..199f2396 100644 --- a/src/main/java/com/tagtraum/perf/gcviewer/imp/DataReaderFactory.java +++ b/src/main/java/com/tagtraum/perf/gcviewer/imp/DataReaderFactory.java @@ -207,6 +207,10 @@ else if (s.contains("starting collection, threshold allocation reached.")) { if (getLogger().isLoggable(Level.INFO)) getLogger().info("File format: IBM i5/OS 1.4.2"); return new DataReaderIBMi5OS1_4_2(gcResource, in); } + else if (s.contains("\ngc ")) { + if (getLogger().isLoggable(Level.INFO)) getLogger().info("File format: Go"); + return new DataReaderGo(gcResource, in); + } return null; } diff --git a/src/main/java/com/tagtraum/perf/gcviewer/imp/DataReaderGo.java b/src/main/java/com/tagtraum/perf/gcviewer/imp/DataReaderGo.java new file mode 100644 index 00000000..a21f1a25 --- /dev/null +++ b/src/main/java/com/tagtraum/perf/gcviewer/imp/DataReaderGo.java @@ -0,0 +1,89 @@ +package com.tagtraum.perf.gcviewer.imp; + +import com.tagtraum.perf.gcviewer.model.AbstractGCEvent; +import com.tagtraum.perf.gcviewer.model.GCEvent; +import com.tagtraum.perf.gcviewer.model.GCModel; +import com.tagtraum.perf.gcviewer.model.GCResource; +import com.tagtraum.perf.gcviewer.util.ParseInformation; +import java.io.IOException; +import java.io.InputStream; +import java.io.LineNumberReader; +import java.io.UnsupportedEncodingException; +import java.util.logging.Level; +import java.util.regex.Matcher; +import java.util.regex.Pattern; + +/** + * Parses GC log output from Go 1.9. + * + * @author Roland Illig + * @see Go documentation + */ +public class DataReaderGo extends AbstractDataReader { + + private static final Pattern GCLINE = Pattern.compile("" + + "gc " + + "(\\d+) " + + "@(\\d+\\.\\d+)s " + + "(\\d+)%: " + + "(\\d+(?:\\.\\d+)?)\\+" + + "(\\d+(?:\\.\\d+)?)\\+" + + "(\\d+(?:\\.\\d+)?) ms clock, " + + "(\\d+(?:\\.\\d+)?)\\+" + + "(\\d+(?:\\.\\d+)?)/" + + "(\\d+(?:\\.\\d+)?)/" + + "(\\d+(?:\\.\\d+)?)\\+" + + "(\\d+(?:\\.\\d+)?) ms cpu, " + + "(\\d+)->" + + "(\\d+)->" + + "(\\d+) MB, " + + "(\\d+) MB goal, " + + "(\\d+) P"); + + public DataReaderGo(GCResource gcResource, InputStream in) throws UnsupportedEncodingException { + super(gcResource, in); + } + + public GCModel read() throws IOException { + if (getLogger().isLoggable(Level.INFO)) getLogger().info("Reading Go format..."); + + try (LineNumberReader in = this.in) { + GCModel model = new GCModel(); + model.setFormat(GCModel.Format.GO); + ParseInformation parsePosition = new ParseInformation(0); + + Matcher matcher = GCLINE.matcher(""); + String line; + while ((line = in.readLine()) != null && shouldContinue()) { + parsePosition.setIndex(0); + parsePosition.setLineNumber(in.getLineNumber()); + if (!matcher.reset(line).matches()) { + continue; + } + + try { + AbstractGCEvent gcEvent = parseMatch(matcher); + model.add(gcEvent); + } catch (Exception pe) { + if (getLogger().isLoggable(Level.WARNING)) getLogger().warning(pe.toString()); + if (getLogger().isLoggable(Level.FINE)) getLogger().log(Level.FINE, pe.getMessage(), pe); + } + } + return model; + } finally { + if (getLogger().isLoggable(Level.INFO)) getLogger().info("Done reading."); + } + } + + private AbstractGCEvent parseMatch(Matcher matcher) { + double relativeTime = Double.parseDouble(matcher.group(2)); + double stopTheWorld1Time = Double.parseDouble(matcher.group(4)) / 1000.0; + double stopTheWorld2Time = Double.parseDouble(matcher.group(6)) / 1000.0; + int preUsed = Integer.parseInt(matcher.group(12)) * 1024; + int alive = Integer.parseInt(matcher.group(13)) * 1024; + int postUsed = Integer.parseInt(matcher.group(14)) * 1024; + + double pause = stopTheWorld1Time + stopTheWorld2Time; + return new GCEvent(relativeTime, preUsed, postUsed, alive, pause, AbstractGCEvent.Type.GC); + } +} diff --git a/src/main/java/com/tagtraum/perf/gcviewer/model/GCModel.java b/src/main/java/com/tagtraum/perf/gcviewer/model/GCModel.java index 2463be59..cad153eb 100644 --- a/src/main/java/com/tagtraum/perf/gcviewer/model/GCModel.java +++ b/src/main/java/com/tagtraum/perf/gcviewer/model/GCModel.java @@ -1065,5 +1065,6 @@ public String toString() { public static final Format IBM_VERBOSE_GC = new Format("IBM -verbose:gc"); public static final Format SUN_1_2_2VERBOSE_GC = new Format("Sun 1.2.2 -verbose:gc"); public static final Format UNIFIED_JVM_LOGGING = new Format("Unified jvm logging -Xlog:gc"); + public static final Format GO = new Format("Go"); } } diff --git a/src/main/java/com/tagtraum/perf/gcviewer/view/AboutDialog.java b/src/main/java/com/tagtraum/perf/gcviewer/view/AboutDialog.java index e158f5b9..29e227fe 100644 --- a/src/main/java/com/tagtraum/perf/gcviewer/view/AboutDialog.java +++ b/src/main/java/com/tagtraum/perf/gcviewer/view/AboutDialog.java @@ -10,7 +10,6 @@ import java.awt.Panel; import java.awt.event.ActionEvent; import java.awt.event.ActionListener; - import javax.swing.BorderFactory; import javax.swing.ImageIcon; import javax.swing.JButton; @@ -48,6 +47,7 @@ public class AboutDialog extends ScreenCenteredDialog implements ActionListener "Neil Gentleman", "Chris Grindstaff", "Michi Gysel", + "Roland Illig", "Johan Kaving", "Maciej Kwiecien", "Henry Lin", diff --git a/src/test/java/com/tagtraum/perf/gcviewer/UnittestHelper.java b/src/test/java/com/tagtraum/perf/gcviewer/UnittestHelper.java index 5e1375cb..45e9bbc1 100644 --- a/src/test/java/com/tagtraum/perf/gcviewer/UnittestHelper.java +++ b/src/test/java/com/tagtraum/perf/gcviewer/UnittestHelper.java @@ -31,6 +31,7 @@ public class UnittestHelper { private static final String FOLDER_OPENJDK = "openjdk"; public enum FOLDER { + GO("go"), HP("hp"), IBM("ibm"), JROCKIT("jrockit"), diff --git a/src/test/java/com/tagtraum/perf/gcviewer/imp/TestDataReaderGo.java b/src/test/java/com/tagtraum/perf/gcviewer/imp/TestDataReaderGo.java new file mode 100644 index 00000000..d16d9713 --- /dev/null +++ b/src/test/java/com/tagtraum/perf/gcviewer/imp/TestDataReaderGo.java @@ -0,0 +1,63 @@ +package com.tagtraum.perf.gcviewer.imp; + +import static org.hamcrest.Matchers.closeTo; +import static org.hamcrest.Matchers.is; +import static org.junit.Assert.assertThat; + +import java.io.ByteArrayInputStream; +import java.io.IOException; +import java.io.InputStream; +import java.util.logging.Level; + +import com.tagtraum.perf.gcviewer.UnittestHelper; +import com.tagtraum.perf.gcviewer.model.AbstractGCEvent; +import com.tagtraum.perf.gcviewer.model.GCModel; +import com.tagtraum.perf.gcviewer.model.GCResource; +import com.tagtraum.perf.gcviewer.model.GcResourceFile; +import org.junit.Test; + +public class TestDataReaderGo { + + @Test + public void test() throws IOException { + TestLogHandler handler = new TestLogHandler(); + handler.setLevel(Level.WARNING); + GCResource gcResource = new GcResourceFile("byteArray"); + gcResource.getLogger().addHandler(handler); + + String gcLog = "" + + "gc starting...\n" // Such a line is not produced by the Go GC; it is just for testing + + "gc 1 @0.058s 0%: 0+1.9+0 ms clock, 0+0.94/1.9/2.9+0 ms cpu, 4->5->1 MB, 5 MB goal, 4 P\n" + + "a line unrelated to GC logging\n" + + "gc 2 @0.073s 3%: 68+0.36+0.51 ms clock, 205+0/16/89+1.5 ms cpu, 11111111111111111111111111111111111->84->42 MB, 86 MB goal, 3 P\n" + + "gc 58 @17.837s 0%: 0.48+17+0 ms clock, 1.9+9.3/7.9/15+0 ms cpu, 30->30->15 MB, 31 MB goal, 4 P\n"; + ByteArrayInputStream in = new ByteArrayInputStream(gcLog.getBytes("US-ASCII")); + DataReader reader = new DataReaderGo(gcResource, in); + GCModel model = reader.read(); + + assertThat("gc 2 -> warning", handler.getCount(), is(1)); + assertThat("size", model.size(), is(2)); + + AbstractGCEvent event1 = model.get(0); + assertThat("timestamp", event1.getTimestamp(), closeTo(0.058, 0.0001)); + assertThat("pause", event1.getPause(), closeTo(0 + 0, 0.1)); + assertThat("preused", event1.getPreUsed(), is(4096)); + assertThat("postused", event1.getPostUsed(), is(1024)); + assertThat("heap", event1.getTotal(), is(5120)); + } + + @Test + public void exampleLog() throws IOException { + TestLogHandler handler = new TestLogHandler(); + handler.setLevel(Level.WARNING); + GCResource gcResource = new GcResourceFile("go1.9.txt"); + gcResource.getLogger().addHandler(handler); + + InputStream in = UnittestHelper.getResourceAsStream(UnittestHelper.FOLDER.GO, gcResource.getResourceName()); + DataReader reader = new DataReaderGo(gcResource, in); + GCModel model = reader.read(); + + assertThat("warnings", handler.getCount(), is(0)); + assertThat("size", model.size(), is(635)); + } +} diff --git a/src/test/java/com/tagtraum/perf/gcviewer/imp/TestDataReaderSun1_8_0G1.java b/src/test/java/com/tagtraum/perf/gcviewer/imp/TestDataReaderSun1_8_0G1.java index ba75e7c7..99799bf6 100644 --- a/src/test/java/com/tagtraum/perf/gcviewer/imp/TestDataReaderSun1_8_0G1.java +++ b/src/test/java/com/tagtraum/perf/gcviewer/imp/TestDataReaderSun1_8_0G1.java @@ -64,6 +64,7 @@ public void fullGcWithDetailedSizes() throws Exception { TestLogHandler handler = new TestLogHandler(); handler.setLevel(Level.WARNING); GCResource gcResource = new GcResourceFile("byteArray"); + gcResource.getLogger().addHandler(handler); ByteArrayInputStream in = new ByteArrayInputStream( ("2014-07-24T13:49:45.090+0400: 92457.841: [Full GC (Allocation Failure) 5811M->3097M(12G), 8.9862292 secs]" diff --git a/src/test/resources/go/go1.9.txt b/src/test/resources/go/go1.9.txt new file mode 100644 index 00000000..36c000b8 --- /dev/null +++ b/src/test/resources/go/go1.9.txt @@ -0,0 +1,638 @@ +gc 1 @0.058s 0%: 0+1.9+0 ms clock, 0+0.94/1.9/2.9+0 ms cpu, 4->4->1 MB, 5 MB goal, 4 P +gc 2 @0.073s 0%: 0+4.8+0.93 ms clock, 0+0.97/4.8/0+3.7 ms cpu, 4->4->2 MB, 5 MB goal, 4 P +gc 3 @0.085s 0%: 0+8.7+0 ms clock, 0+0/8.7/5.8+0 ms cpu, 4->5->3 MB, 5 MB goal, 4 P +gc 4 @0.110s 0%: 0+11+0 ms clock, 0+1.9/11/4.9+0 ms cpu, 7->7->4 MB, 8 MB goal, 4 P +gc 5 @0.139s 0%: 0+17+0 ms clock, 0+1.9/17/0+0 ms cpu, 8->8->5 MB, 9 MB goal, 4 P +gc 6 @0.180s 0%: 0+7.7+0 ms clock, 0+4.9/6.8/13+0 ms cpu, 10->11->5 MB, 11 MB goal, 4 P +gc 7 @0.208s 0%: 0+16+0 ms clock, 0+5.0/15/2.9+0 ms cpu, 11->11->7 MB, 12 MB goal, 4 P +gc 8 @0.326s 0%: 0+20+0 ms clock, 0+4.4/20/0+0 ms cpu, 13->14->7 MB, 14 MB goal, 4 P +gc 9 @1.111s 0%: 0+18+0 ms clock, 0+5.4/17/3.9+0 ms cpu, 14->15->9 MB, 15 MB goal, 4 P +gc 10 @1.411s 0%: 0+11+0.45 ms clock, 0+0.95/10/21+1.8 ms cpu, 18->18->12 MB, 19 MB goal, 4 P +gc 11 @1.980s 0%: 0+21+0 ms clock, 0+8.8/10/6.8+0 ms cpu, 24->24->12 MB, 25 MB goal, 4 P +gc 12 @2.435s 0%: 0+21+0 ms clock, 0+9.7/19/4.3+0 ms cpu, 25->25->13 MB, 26 MB goal, 4 P +gc 13 @2.869s 0%: 0+13+1.0 ms clock, 0+3.8/13/16+4.0 ms cpu, 26->27->13 MB, 27 MB goal, 4 P +gc 14 @3.158s 0%: 0+16+0 ms clock, 0+6.8/16/7.8+0 ms cpu, 26->26->13 MB, 27 MB goal, 4 P +gc 15 @3.535s 0%: 0+17+0 ms clock, 0+3.9/17/9.9+0 ms cpu, 26->26->13 MB, 27 MB goal, 4 P +gc 16 @3.962s 0%: 0+14+0 ms clock, 0+4.8/14/16+0 ms cpu, 26->27->13 MB, 27 MB goal, 4 P +gc 17 @4.456s 0%: 0+22+0 ms clock, 0+5.8/22/1.9+0 ms cpu, 27->27->13 MB, 28 MB goal, 4 P +gc 18 @5.042s 0%: 0+21+0 ms clock, 0+11/19/1.9+0 ms cpu, 27->27->13 MB, 28 MB goal, 4 P +gc 19 @5.429s 0%: 0+28+0 ms clock, 0+2.4/28/0.54+0 ms cpu, 26->27->14 MB, 27 MB goal, 4 P +gc 20 @5.761s 0%: 0+10+0 ms clock, 0+1.0/10/21+0 ms cpu, 28->28->14 MB, 29 MB goal, 4 P +gc 21 @6.100s 0%: 0+17+0 ms clock, 0+14/17/0+0 ms cpu, 27->28->13 MB, 28 MB goal, 4 P +gc 22 @6.378s 0%: 0+19+0.93 ms clock, 0+7.7/18/0+3.7 ms cpu, 26->26->14 MB, 27 MB goal, 4 P +gc 23 @6.603s 0%: 0+11+0 ms clock, 0+4.8/11/24+0 ms cpu, 27->27->15 MB, 28 MB goal, 4 P +gc 24 @6.951s 0%: 0+14+0 ms clock, 0+2.9/14/13+0 ms cpu, 30->30->14 MB, 31 MB goal, 4 P +gc 25 @7.307s 0%: 0+13+0 ms clock, 0+1.9/12/18+0 ms cpu, 27->28->13 MB, 28 MB goal, 4 P +gc 26 @7.607s 0%: 0+9.7+0.97 ms clock, 0+1.9/9.7/19+3.9 ms cpu, 27->27->13 MB, 28 MB goal, 4 P +gc 27 @7.873s 0%: 0+11+0 ms clock, 0+0/11/22+0 ms cpu, 27->27->14 MB, 28 MB goal, 4 P +gc 28 @8.184s 0%: 0+18+0 ms clock, 0+9.7/18/1.9+0 ms cpu, 28->29->14 MB, 29 MB goal, 4 P +gc 29 @8.467s 0%: 0+25+0 ms clock, 0+5.8/21/3.8+0 ms cpu, 27->28->14 MB, 28 MB goal, 4 P +gc 30 @8.788s 0%: 0+19+0 ms clock, 0+7.8/19/0+0 ms cpu, 28->28->14 MB, 29 MB goal, 4 P +gc 31 @9.199s 0%: 0+15+0 ms clock, 0+6.9/15/6.9+0 ms cpu, 27->28->14 MB, 28 MB goal, 4 P +gc 32 @9.526s 0%: 0+16+0 ms clock, 0+2.4/16/17+0 ms cpu, 27->27->15 MB, 28 MB goal, 4 P +gc 33 @9.955s 0%: 0+23+0 ms clock, 0+4.9/23/0.96+0 ms cpu, 29->29->15 MB, 30 MB goal, 4 P +gc 34 @10.199s 0%: 0.97+15+0 ms clock, 3.9+7.7/15/2.9+0 ms cpu, 29->30->14 MB, 30 MB goal, 4 P +gc 35 @10.444s 0%: 0+9.8+0 ms clock, 0+5.8/8.8/17+0 ms cpu, 28->29->15 MB, 29 MB goal, 4 P +gc 36 @10.667s 0%: 0+17+0 ms clock, 0+7.8/17/7.7+0 ms cpu, 29->30->15 MB, 30 MB goal, 4 P +gc 37 @10.912s 0%: 0+11+0.98 ms clock, 0+6.7/11/12+3.9 ms cpu, 29->30->15 MB, 30 MB goal, 4 P +gc 38 @11.151s 0%: 0+11+0 ms clock, 0+0.93/9.8/19+0 ms cpu, 29->29->14 MB, 30 MB goal, 4 P +gc 39 @11.389s 0%: 0+17+0 ms clock, 0+2.9/15/12+0 ms cpu, 29->29->15 MB, 30 MB goal, 4 P +gc 40 @11.654s 0%: 0+21+0 ms clock, 0+12/12/3.7+0 ms cpu, 29->30->14 MB, 30 MB goal, 4 P +gc 41 @11.891s 0%: 0+17+0 ms clock, 0+4.9/16/8.8+0 ms cpu, 28->28->14 MB, 29 MB goal, 4 P +gc 42 @12.127s 0%: 0+14+0 ms clock, 0+6.0/14/12+0 ms cpu, 29->29->14 MB, 30 MB goal, 4 P +gc 43 @12.348s 0%: 0+19+0.97 ms clock, 0+5.8/19/11+3.9 ms cpu, 28->29->15 MB, 29 MB goal, 4 P +gc 44 @12.567s 0%: 0+14+0.97 ms clock, 0+4.8/14/9.7+3.9 ms cpu, 29->30->14 MB, 30 MB goal, 4 P +gc 45 @12.792s 0%: 0+17+0 ms clock, 0+13/16/0.97+0 ms cpu, 29->29->15 MB, 30 MB goal, 4 P +gc 46 @13.059s 0%: 0+18+1.0 ms clock, 0+6.8/17/5.7+4.0 ms cpu, 30->30->15 MB, 31 MB goal, 4 P +gc 47 @13.356s 0%: 0+11+0 ms clock, 0+0.97/10/22+0 ms cpu, 29->29->15 MB, 30 MB goal, 4 P +gc 48 @13.668s 0%: 0+12+0 ms clock, 0+0.97/9.7/21+0 ms cpu, 30->30->15 MB, 31 MB goal, 4 P +gc 49 @14.000s 0%: 0+27+0 ms clock, 0+13/13/0.97+0 ms cpu, 29->29->14 MB, 30 MB goal, 4 P +gc 50 @14.346s 0%: 0+14+0 ms clock, 0+1.9/14/15+0 ms cpu, 28->28->14 MB, 29 MB goal, 4 P +gc 51 @15.045s 0%: 0+17+0.98 ms clock, 0+0.98/13/26+3.9 ms cpu, 29->29->14 MB, 30 MB goal, 4 P +gc 52 @15.659s 0%: 0+13+0 ms clock, 0+0.97/12/20+0 ms cpu, 28->28->14 MB, 29 MB goal, 4 P +gc 53 @16.041s 0%: 0+21+0 ms clock, 0+4.8/19/3.5+0 ms cpu, 28->28->14 MB, 29 MB goal, 4 P +gc 54 @16.442s 0%: 0+21+0 ms clock, 0+15/21/4.0+0 ms cpu, 29->29->14 MB, 30 MB goal, 4 P +gc 55 @16.858s 0%: 0+17+1.0 ms clock, 0+10/17/1.9+4.0 ms cpu, 28->29->14 MB, 29 MB goal, 4 P +gc 56 @17.179s 0%: 0+14+0 ms clock, 0+3.0/13/16+0 ms cpu, 28->29->15 MB, 29 MB goal, 4 P +gc 57 @17.512s 0%: 0+27+0.50 ms clock, 0+4.9/24/1.8+2.0 ms cpu, 30->30->15 MB, 31 MB goal, 4 P +gc 58 @17.837s 0%: 0.48+17+0 ms clock, 1.9+9.3/7.9/15+0 ms cpu, 30->30->15 MB, 31 MB goal, 4 P +gc 59 @18.407s 0%: 0+12+0 ms clock, 0+0.97/10/22+0 ms cpu, 29->29->15 MB, 30 MB goal, 4 P +gc 60 @18.789s 0%: 0+25+0 ms clock, 0+4.9/24/2.0+0 ms cpu, 29->30->15 MB, 30 MB goal, 4 P +gc 61 @19.251s 0%: 0+16+0 ms clock, 0+11/12/7.9+0 ms cpu, 29->30->15 MB, 30 MB goal, 4 P +gc 62 @19.603s 0%: 0+16+0 ms clock, 0+4.7/15/16+0 ms cpu, 29->29->15 MB, 30 MB goal, 4 P +gc 63 @20.039s 0%: 0+24+0 ms clock, 0+9.4/24/0.53+0 ms cpu, 30->31->16 MB, 31 MB goal, 4 P +gc 64 @20.435s 0%: 0+16+0 ms clock, 0+12/16/0.99+0 ms cpu, 32->32->15 MB, 33 MB goal, 4 P +gc 65 @20.730s 0%: 0+20+0 ms clock, 0+10/18/5.8+0 ms cpu, 29->30->16 MB, 30 MB goal, 4 P +gc 66 @21.091s 0%: 0+30+0.90 ms clock, 0+10/11/5.8+3.6 ms cpu, 31->32->16 MB, 32 MB goal, 4 P +gc 67 @21.662s 0%: 0+22+0 ms clock, 0+10/22/0+0 ms cpu, 31->32->16 MB, 32 MB goal, 4 P +gc 68 @22.009s 0%: 0+30+0.54 ms clock, 0+6.8/24/0+2.1 ms cpu, 31->31->16 MB, 32 MB goal, 4 P +gc 69 @22.382s 0%: 0+11+0 ms clock, 0+3.9/10/20+0 ms cpu, 32->32->15 MB, 33 MB goal, 4 P +gc 70 @22.688s 0%: 0+19+0 ms clock, 0+7.8/18/3.9+0 ms cpu, 30->31->16 MB, 31 MB goal, 4 P +gc 71 @23.029s 0%: 0+22+0 ms clock, 0+12/22/0+0 ms cpu, 31->31->16 MB, 32 MB goal, 4 P +gc 72 @23.361s 0%: 0+18+0 ms clock, 0+9.8/17/2.9+0 ms cpu, 31->32->16 MB, 32 MB goal, 4 P +gc 73 @23.632s 0%: 0+10+0 ms clock, 0+2.4/10/21+0 ms cpu, 31->32->15 MB, 32 MB goal, 4 P +gc 74 @23.935s 0%: 0+14+0 ms clock, 0+2.5/13/22+0 ms cpu, 31->31->15 MB, 32 MB goal, 4 P +gc 75 @24.289s 0%: 0+20+0 ms clock, 0+12/20/2.9+0 ms cpu, 30->31->15 MB, 31 MB goal, 4 P +gc 76 @24.572s 0%: 0+12+0 ms clock, 0+6.8/11/23+0 ms cpu, 30->30->16 MB, 31 MB goal, 4 P +gc 77 @24.947s 0%: 0+16+0.95 ms clock, 0+4.8/15/6.9+3.8 ms cpu, 32->32->15 MB, 33 MB goal, 4 P +gc 78 @25.373s 0%: 0+22+0.96 ms clock, 0+5.8/22/1.0+3.8 ms cpu, 30->30->15 MB, 31 MB goal, 4 P +gc 79 @25.659s 0%: 0+10+0 ms clock, 0+7.7/10/20+0 ms cpu, 31->31->16 MB, 32 MB goal, 4 P +gc 80 @25.977s 0%: 0+13+0 ms clock, 0+2.9/13/15+0 ms cpu, 32->32->15 MB, 33 MB goal, 4 P +gc 81 @26.414s 0%: 0+19+0 ms clock, 0+13/17/3.9+0 ms cpu, 30->31->15 MB, 31 MB goal, 4 P +gc 82 @26.822s 0%: 0+32+0.97 ms clock, 0+16/9.8/5.9+3.8 ms cpu, 30->31->15 MB, 31 MB goal, 4 P +gc 83 @27.227s 0%: 0+18+0.45 ms clock, 0+13/18/0+1.8 ms cpu, 30->31->17 MB, 31 MB goal, 4 P +gc 84 @27.577s 0%: 0+17+0.93 ms clock, 0+6.8/17/6.8+3.7 ms cpu, 33->33->16 MB, 34 MB goal, 4 P +gc 85 @28.139s 0%: 0+23+0 ms clock, 0+0/23/3.0+0 ms cpu, 31->32->15 MB, 32 MB goal, 4 P +gc 86 @28.591s 0%: 0+19+0 ms clock, 0+8.7/17/2.8+0 ms cpu, 30->31->15 MB, 31 MB goal, 4 P +gc 87 @28.961s 0%: 0+12+0 ms clock, 0+0.96/11/23+0 ms cpu, 30->30->16 MB, 31 MB goal, 4 P +gc 88 @29.304s 0%: 0+17+0 ms clock, 0+8.8/17/1.9+0 ms cpu, 31->31->16 MB, 32 MB goal, 4 P +gc 89 @29.690s 0%: 0+18+0 ms clock, 0+11/18/2.8+0 ms cpu, 31->31->15 MB, 32 MB goal, 4 P +gc 90 @30.042s 0%: 0+24+0 ms clock, 0+0/22/8.9+0 ms cpu, 30->31->16 MB, 31 MB goal, 4 P +gc 91 @30.387s 0%: 0+17+0.96 ms clock, 0+11/16/1.9+3.8 ms cpu, 31->32->15 MB, 32 MB goal, 4 P +gc 92 @30.722s 0%: 0+15+0 ms clock, 0+8.7/14/4.9+0 ms cpu, 31->31->15 MB, 32 MB goal, 4 P +gc 93 @31.186s 0%: 0+19+0 ms clock, 0+8.7/19/2.9+0 ms cpu, 30->30->16 MB, 31 MB goal, 4 P +gc 94 @31.532s 0%: 0+11+0 ms clock, 0+1.0/11/23+0 ms cpu, 31->31->16 MB, 32 MB goal, 4 P +gc 95 @31.830s 0%: 0+17+0.45 ms clock, 0+9.4/17/5.8+1.8 ms cpu, 31->32->16 MB, 32 MB goal, 4 P +gc 96 @32.225s 0%: 0+21+0.48 ms clock, 0+10/13/8.7+1.9 ms cpu, 31->32->15 MB, 32 MB goal, 4 P +gc 97 @32.717s 0%: 0+32+0 ms clock, 0+13/13/0.97+0 ms cpu, 30->30->15 MB, 31 MB goal, 4 P +gc 98 @33.220s 0%: 0+24+0 ms clock, 0+2.9/23/4.8+0 ms cpu, 30->31->15 MB, 31 MB goal, 4 P +gc 99 @33.859s 0%: 0+16+0 ms clock, 0+0/16/16+0 ms cpu, 31->31->16 MB, 32 MB goal, 4 P +gc 100 @34.329s 0%: 0+17+0.50 ms clock, 0+3.9/16/11+2.0 ms cpu, 31->31->16 MB, 32 MB goal, 4 P +gc 101 @34.860s 0%: 0+20+0.93 ms clock, 0+7.8/20/1.9+3.7 ms cpu, 31->31->15 MB, 32 MB goal, 4 P +gc 102 @35.594s 0%: 0+11+0 ms clock, 0+1.0/10/21+0 ms cpu, 31->31->16 MB, 32 MB goal, 4 P +gc 103 @36.539s 0%: 0+11+0 ms clock, 0+0.97/11/23+0 ms cpu, 31->32->16 MB, 32 MB goal, 4 P +gc 104 @37.086s 0%: 0+12+0 ms clock, 0+1.9/11/20+0 ms cpu, 32->32->16 MB, 33 MB goal, 4 P +gc 105 @37.965s 0%: 0+15+0 ms clock, 0+5.8/15/11+0 ms cpu, 31->31->16 MB, 32 MB goal, 4 P +gc 106 @38.530s 0%: 0+13+0 ms clock, 0+4.8/13/11+0 ms cpu, 31->32->16 MB, 32 MB goal, 4 P +gc 107 @39.013s 0%: 0+22+0 ms clock, 0+1.9/20/3.9+0 ms cpu, 31->32->16 MB, 32 MB goal, 4 P +gc 108 @39.983s 0%: 0+11+0.98 ms clock, 0+0.93/10/21+3.9 ms cpu, 31->32->16 MB, 32 MB goal, 4 P +gc 109 @40.603s 0%: 0+19+0 ms clock, 0+7.8/18/3.9+0 ms cpu, 32->32->16 MB, 33 MB goal, 4 P +gc 110 @41.144s 0%: 0+25+0 ms clock, 0+13/13/2.9+0 ms cpu, 32->32->16 MB, 33 MB goal, 4 P +gc 111 @41.572s 0%: 0+12+0 ms clock, 0+0/12/25+0 ms cpu, 32->32->17 MB, 33 MB goal, 4 P +gc 112 @42.043s 0%: 0+14+0 ms clock, 0+2.5/14/17+0 ms cpu, 34->34->17 MB, 35 MB goal, 4 P +gc 113 @42.495s 0%: 0+29+0 ms clock, 0+8.7/21/5.9+0 ms cpu, 33->33->17 MB, 34 MB goal, 4 P +gc 114 @42.979s 0%: 0+21+0 ms clock, 0+6.8/21/1.9+0 ms cpu, 34->34->17 MB, 35 MB goal, 4 P +gc 115 @43.517s 0%: 0+26+0 ms clock, 0+1.9/26/3.9+0 ms cpu, 33->33->16 MB, 34 MB goal, 4 P +gc 116 @44.224s 0%: 0+18+0 ms clock, 0+0/16/11+0 ms cpu, 33->33->16 MB, 34 MB goal, 4 P +gc 117 @44.906s 0%: 0+12+0 ms clock, 0+0.97/10/21+0 ms cpu, 32->32->17 MB, 33 MB goal, 4 P +gc 118 @45.286s 0%: 0+17+0 ms clock, 0+10/17/1.9+0 ms cpu, 33->33->16 MB, 34 MB goal, 4 P +gc 119 @45.722s 0%: 0+18+0 ms clock, 0+11/17/2.9+0 ms cpu, 32->33->16 MB, 33 MB goal, 4 P +gc 120 @46.162s 0%: 0+22+0 ms clock, 0+6.3/21/1.5+0 ms cpu, 33->33->17 MB, 34 MB goal, 4 P +gc 121 @46.598s 0%: 0+16+0 ms clock, 0+4.8/16/8.8+0 ms cpu, 33->33->16 MB, 34 MB goal, 4 P +gc 122 @47.000s 0%: 0+18+0 ms clock, 0+14/8.7/11+0 ms cpu, 32->33->16 MB, 33 MB goal, 4 P +gc 123 @47.370s 0%: 0+25+0 ms clock, 0+1.9/23/4.9+0 ms cpu, 32->33->17 MB, 33 MB goal, 4 P +gc 124 @47.915s 0%: 0+19+0.97 ms clock, 0+8.7/19/5.8+3.9 ms cpu, 33->34->18 MB, 34 MB goal, 4 P +gc 125 @48.569s 0%: 0+10+0 ms clock, 0+4.8/9.8/20+0 ms cpu, 35->35->17 MB, 36 MB goal, 4 P +gc 126 @49.052s 0%: 0+28+0.91 ms clock, 0+0.45/28/0+3.6 ms cpu, 33->33->17 MB, 34 MB goal, 4 P +gc 127 @49.696s 0%: 0+14+0 ms clock, 0+0/14/16+0 ms cpu, 33->33->16 MB, 34 MB goal, 4 P +gc 128 @50.096s 0%: 0+25+0 ms clock, 0+0/23/8.7+0 ms cpu, 33->33->17 MB, 34 MB goal, 4 P +gc 129 @50.738s 0%: 0+19+0 ms clock, 0+0/16/21+0 ms cpu, 34->34->17 MB, 35 MB goal, 4 P +gc 130 @51.368s 0%: 0+25+0 ms clock, 0+0/25/2.9+0 ms cpu, 34->34->17 MB, 35 MB goal, 4 P +gc 131 @51.963s 0%: 0+21+0 ms clock, 0+14/20/1.0+0 ms cpu, 33->33->17 MB, 34 MB goal, 4 P +gc 132 @52.382s 0%: 0+15+0 ms clock, 0+0/15/17+0 ms cpu, 33->34->17 MB, 34 MB goal, 4 P +gc 133 @52.869s 0%: 0+11+0 ms clock, 0+0.97/11/23+0 ms cpu, 33->34->17 MB, 34 MB goal, 4 P +gc 134 @53.280s 0%: 0+23+0 ms clock, 0+3.9/22/7.8+0 ms cpu, 33->34->18 MB, 34 MB goal, 4 P +gc 135 @53.680s 0%: 0+14+0 ms clock, 0+6.8/14/23+0 ms cpu, 35->35->18 MB, 36 MB goal, 4 P +gc 136 @54.262s 0%: 0+26+0 ms clock, 0+0/26/0.49+0 ms cpu, 35->35->17 MB, 36 MB goal, 4 P +gc 137 @54.699s 0%: 0+16+0 ms clock, 0+7.7/7.8/17+0 ms cpu, 34->34->17 MB, 35 MB goal, 4 P +gc 138 @55.248s 0%: 0+12+0 ms clock, 0+3.4/12/25+0 ms cpu, 34->34->18 MB, 35 MB goal, 4 P +gc 139 @55.817s 0%: 0+24+0 ms clock, 0+3.9/22/4.8+0 ms cpu, 36->36->17 MB, 37 MB goal, 4 P +gc 140 @56.412s 0%: 0+21+0 ms clock, 0+8.8/21/0+0 ms cpu, 34->34->18 MB, 35 MB goal, 4 P +gc 141 @57.014s 0%: 0+11+0 ms clock, 0+3.9/10/22+0 ms cpu, 35->35->18 MB, 36 MB goal, 4 P +gc 142 @57.433s 0%: 0+28+0 ms clock, 0+0/28/1.9+0 ms cpu, 35->35->18 MB, 36 MB goal, 4 P +gc 143 @58.081s 0%: 0+20+0 ms clock, 0+3.9/20/5.9+0 ms cpu, 35->36->17 MB, 36 MB goal, 4 P +gc 144 @58.997s 0%: 0+19+0 ms clock, 0+8.9/19/1.9+0 ms cpu, 34->35->18 MB, 35 MB goal, 4 P +gc 145 @59.626s 0%: 0+22+0 ms clock, 0+6.8/21/3.9+0 ms cpu, 35->35->18 MB, 36 MB goal, 4 P +gc 146 @60.349s 0%: 0+23+0.53 ms clock, 0+8.4/21/4.8+2.1 ms cpu, 35->36->18 MB, 36 MB goal, 4 P +gc 147 @61.099s 0%: 0+24+0 ms clock, 0+3.9/24/6.9+0 ms cpu, 37->37->18 MB, 38 MB goal, 4 P +gc 148 @61.607s 0%: 0+17+0 ms clock, 0+13/17/3.9+0 ms cpu, 36->36->19 MB, 37 MB goal, 4 P +gc 149 @62.083s 0%: 0+12+0.95 ms clock, 0+0.97/12/25+3.8 ms cpu, 38->38->18 MB, 39 MB goal, 4 P +gc 150 @62.426s 0%: 0+11+0.93 ms clock, 0+7.8/11/15+3.7 ms cpu, 36->36->18 MB, 37 MB goal, 4 P +gc 151 @62.703s 0%: 0+27+0 ms clock, 0+1.0/8.8/21+0 ms cpu, 36->36->20 MB, 37 MB goal, 4 P +gc 152 @63.025s 0%: 0+29+0 ms clock, 0+15/15/3.9+0 ms cpu, 40->41->22 MB, 41 MB goal, 4 P +gc 153 @63.658s 0%: 0+12+0.50 ms clock, 0+2.0/12/25+2.0 ms cpu, 44->44->18 MB, 45 MB goal, 4 P +gc 154 @64.732s 0%: 0+74+0 ms clock, 0+20/71/9.7+0 ms cpu, 36->37->19 MB, 37 MB goal, 4 P +gc 155 @66.544s 0%: 0+49+0.45 ms clock, 0+20/40/32+1.8 ms cpu, 37->38->19 MB, 38 MB goal, 4 P +gc 156 @67.581s 0%: 0+25+0.53 ms clock, 0+16/11/7.7+2.1 ms cpu, 37->37->18 MB, 38 MB goal, 4 P +gc 157 @69.059s 0%: 0+71+48 ms clock, 0+32/48/37+192 ms cpu, 36->37->19 MB, 37 MB goal, 4 P +gc 158 @70.114s 0%: 0+20+0 ms clock, 0+12/19/4.9+0 ms cpu, 37->38->18 MB, 38 MB goal, 4 P +gc 159 @70.663s 0%: 0+72+1.9 ms clock, 0+0/70/64+7.8 ms cpu, 36->37->18 MB, 37 MB goal, 4 P +gc 160 @72.101s 0%: 0+36+0 ms clock, 0+5.8/31/16+0 ms cpu, 36->36->18 MB, 37 MB goal, 4 P +gc 161 @74.194s 0%: 0+29+0 ms clock, 0+1.5/25/31+0 ms cpu, 36->37->18 MB, 37 MB goal, 4 P +gc 162 @78.017s 0%: 0+97+0.51 ms clock, 0+49/62/12+2.0 ms cpu, 36->36->19 MB, 37 MB goal, 4 P +gc 163 @80.498s 0%: 0+94+0.45 ms clock, 0+47/45/17+1.8 ms cpu, 38->38->19 MB, 39 MB goal, 4 P +gc 164 @84.631s 0%: 0+62+2.0 ms clock, 0+20/41/0.50+8.0 ms cpu, 38->38->19 MB, 39 MB goal, 4 P +gc 165 @86.364s 0%: 0+25+0 ms clock, 0+3.9/25/50+0 ms cpu, 37->37->20 MB, 38 MB goal, 4 P +gc 166 @87.496s 0%: 0+25+0 ms clock, 0+0/25/4.5+0 ms cpu, 40->41->19 MB, 41 MB goal, 4 P +gc 167 @88.462s 0%: 0+22+0 ms clock, 0+10/22/3.9+0 ms cpu, 37->38->19 MB, 38 MB goal, 4 P +gc 168 @89.770s 0%: 0+26+0.50 ms clock, 0+11/24/4.4+2.0 ms cpu, 38->38->19 MB, 39 MB goal, 4 P +gc 169 @90.550s 0%: 0+23+0 ms clock, 0+13/23/2.4+0 ms cpu, 37->38->19 MB, 38 MB goal, 4 P +gc 170 @92.348s 0%: 0+25+0 ms clock, 0+4.9/25/2.9+0 ms cpu, 37->38->19 MB, 38 MB goal, 4 P +gc 171 @93.664s 0%: 0+27+0 ms clock, 0+16/26/26+0 ms cpu, 37->37->19 MB, 38 MB goal, 4 P +gc 172 @95.118s 0%: 0+32+0 ms clock, 0+25/31/19+0 ms cpu, 37->38->19 MB, 38 MB goal, 4 P +gc 173 @96.351s 0%: 0+26+0 ms clock, 0+1.4/25/3.0+0 ms cpu, 37->37->19 MB, 38 MB goal, 4 P +gc 174 @97.346s 0%: 0+29+0 ms clock, 0+4.9/29/40+0 ms cpu, 37->38->19 MB, 38 MB goal, 4 P +gc 175 @98.253s 0%: 0+22+0 ms clock, 0+8.7/21/2.9+0 ms cpu, 37->38->19 MB, 38 MB goal, 4 P +gc 176 @99.476s 0%: 0+18+0 ms clock, 0+7.8/13/14+0 ms cpu, 37->38->19 MB, 38 MB goal, 4 P +gc 177 @99.963s 0%: 0+11+0 ms clock, 0+0/11/23+0 ms cpu, 38->38->20 MB, 39 MB goal, 4 P +gc 178 @100.522s 0%: 0+21+0 ms clock, 0+0.97/21/7.8+0 ms cpu, 39->39->19 MB, 40 MB goal, 4 P +gc 179 @101.148s 0%: 0+21+0 ms clock, 0+8.7/20/4.8+0 ms cpu, 38->38->19 MB, 39 MB goal, 4 P +gc 180 @102.040s 0%: 0+46+0 ms clock, 0+4.9/40/22+0 ms cpu, 38->38->19 MB, 39 MB goal, 4 P +gc 181 @102.730s 0%: 0+21+1.0 ms clock, 0+10/21/3.5+4.0 ms cpu, 38->39->20 MB, 39 MB goal, 4 P +gc 182 @103.149s 0%: 0+26+0 ms clock, 0+0/26/34+0 ms cpu, 40->40->25 MB, 41 MB goal, 4 P +gc 183 @104.223s 0%: 0+24+0 ms clock, 0+0/23/23+0 ms cpu, 50->50->22 MB, 51 MB goal, 4 P +gc 184 @104.797s 0%: 0+21+0 ms clock, 0+14/20/5.9+0 ms cpu, 43->44->22 MB, 44 MB goal, 4 P +gc 185 @105.594s 0%: 0+110+0.50 ms clock, 0+14/75/46+2.0 ms cpu, 44->45->20 MB, 45 MB goal, 4 P +gc 186 @106.629s 0%: 0+24+0 ms clock, 0+4.9/24/3.9+0 ms cpu, 39->39->20 MB, 40 MB goal, 4 P +gc 187 @107.243s 0%: 0+23+0 ms clock, 0+5.9/21/1.8+0 ms cpu, 40->40->20 MB, 41 MB goal, 4 P +gc 188 @107.829s 0%: 0+27+0 ms clock, 0+4.9/22/19+0 ms cpu, 39->40->20 MB, 40 MB goal, 4 P +gc 189 @108.389s 0%: 0+19+0 ms clock, 0+12/19/1.9+0 ms cpu, 39->40->20 MB, 41 MB goal, 4 P +gc 190 @109.031s 0%: 0+20+0 ms clock, 0+0/20/18+0 ms cpu, 39->40->20 MB, 40 MB goal, 4 P +gc 191 @109.553s 0%: 0+15+0.95 ms clock, 0+8.7/14/13+3.8 ms cpu, 40->40->20 MB, 41 MB goal, 4 P +gc 192 @110.233s 0%: 0+16+0 ms clock, 0+0.96/15/17+0 ms cpu, 40->40->20 MB, 41 MB goal, 4 P +gc 193 @110.722s 0%: 0+17+0 ms clock, 0+3.9/17/13+0 ms cpu, 40->40->21 MB, 41 MB goal, 4 P +gc 194 @111.176s 0%: 0+13+0 ms clock, 0+3.4/12/21+0 ms cpu, 41->41->20 MB, 42 MB goal, 4 P +gc 195 @111.614s 0%: 0+12+0 ms clock, 0+8.7/12/13+0 ms cpu, 40->40->20 MB, 41 MB goal, 4 P +gc 196 @112.105s 0%: 0+28+0 ms clock, 0+19/21/6.8+0 ms cpu, 40->41->21 MB, 41 MB goal, 4 P +gc 197 @112.589s 0%: 0+30+0 ms clock, 0+15/14/1.5+0 ms cpu, 41->41->21 MB, 42 MB goal, 4 P +gc 198 @113.218s 0%: 0.97+19+0 ms clock, 3.9+4.8/19/8.7+0 ms cpu, 41->41->20 MB, 42 MB goal, 4 P +gc 199 @113.714s 0%: 0.98+19+0 ms clock, 3.9+0/18/13+0 ms cpu, 40->40->21 MB, 41 MB goal, 4 P +gc 200 @114.287s 0%: 0+21+0.99 ms clock, 0+6.0/19/4.4+3.9 ms cpu, 40->41->21 MB, 42 MB goal, 4 P +gc 201 @114.732s 0%: 0+12+0 ms clock, 0+1.9/12/25+0 ms cpu, 41->41->21 MB, 42 MB goal, 4 P +gc 202 @115.092s 0%: 0+12+0 ms clock, 0+3.9/12/24+0 ms cpu, 41->41->21 MB, 42 MB goal, 4 P +gc 203 @115.450s 0%: 0+12+0 ms clock, 0+5.8/10/20+0 ms cpu, 41->42->21 MB, 42 MB goal, 4 P +gc 204 @115.803s 0%: 0+18+0 ms clock, 0+10/16/12+0 ms cpu, 41->42->21 MB, 42 MB goal, 4 P +gc 205 @116.176s 0%: 0+19+0 ms clock, 0+12/18/4.0+0 ms cpu, 41->42->21 MB, 42 MB goal, 4 P +gc 206 @116.547s 0%: 0+22+0 ms clock, 0+7.8/22/4.8+0 ms cpu, 41->42->21 MB, 42 MB goal, 4 P +gc 207 @117.155s 0%: 0+17+0 ms clock, 0+7.8/17/7.9+0 ms cpu, 42->42->21 MB, 43 MB goal, 4 P +gc 208 @118.047s 0%: 0+26+0 ms clock, 0+1.0/25/12+0 ms cpu, 41->41->21 MB, 42 MB goal, 4 P +gc 209 @120.130s 0%: 0+20+0 ms clock, 0+3.9/20/7.8+0 ms cpu, 42->43->21 MB, 43 MB goal, 4 P +gc 210 @120.773s 0%: 0+16+0.97 ms clock, 0+9.7/16/7.9+3.9 ms cpu, 41->41->21 MB, 42 MB goal, 4 P +gc 211 @121.436s 0%: 0+25+0 ms clock, 0+11/21/0+0 ms cpu, 41->41->22 MB, 42 MB goal, 4 P +gc 212 @122.217s 0%: 0+22+0 ms clock, 0+6.8/21/4.8+0 ms cpu, 43->43->21 MB, 44 MB goal, 4 P +gc 213 @123.188s 0%: 0+28+0.93 ms clock, 0+0/11/20+3.7 ms cpu, 42->42->21 MB, 43 MB goal, 4 P +gc 214 @123.673s 0%: 0+21+0 ms clock, 0+11/20/3.9+0 ms cpu, 42->42->21 MB, 43 MB goal, 4 P +gc 215 @124.392s 0%: 0+19+0 ms clock, 0+11/19/12+0 ms cpu, 42->42->22 MB, 43 MB goal, 4 P +gc 216 @126.093s 0%: 0+25+0 ms clock, 0+6.0/24/0.54+0 ms cpu, 43->43->21 MB, 44 MB goal, 4 P +gc 217 @126.901s 0%: 0+25+0 ms clock, 0+6.7/25/4.3+0 ms cpu, 42->42->22 MB, 43 MB goal, 4 P +gc 218 @127.622s 0%: 0+21+1.9 ms clock, 0+10/20/24+7.8 ms cpu, 42->43->22 MB, 44 MB goal, 4 P +gc 219 @128.388s 0%: 0.95+24+0 ms clock, 3.8+13/23/0.95+0 ms cpu, 43->43->22 MB, 44 MB goal, 4 P +gc 220 @129.136s 0%: 0+21+0 ms clock, 0+9.6/21/5.8+0 ms cpu, 44->44->22 MB, 45 MB goal, 4 P +gc 221 @130.107s 0%: 0+17+0 ms clock, 0+7.8/17/13+0 ms cpu, 44->44->22 MB, 45 MB goal, 4 P +gc 222 @130.751s 0%: 0+30+0 ms clock, 0+9.8/18/6.9+0 ms cpu, 43->43->22 MB, 44 MB goal, 4 P +gc 223 @131.799s 0%: 0+23+1.0 ms clock, 0+0/21/11+4.0 ms cpu, 43->44->22 MB, 44 MB goal, 4 P +gc 224 @132.571s 0%: 0+17+0 ms clock, 0+1.0/16/24+0 ms cpu, 43->43->22 MB, 44 MB goal, 4 P +gc 225 @133.343s 0%: 0+18+0 ms clock, 0+9.8/16/7.8+0 ms cpu, 44->44->22 MB, 45 MB goal, 4 P +gc 226 @134.385s 0%: 0+28+0.92 ms clock, 0+0.92/25/4.8+3.7 ms cpu, 43->44->22 MB, 44 MB goal, 4 P +gc 227 @135.329s 0%: 0+41+0 ms clock, 0+14/40/33+0 ms cpu, 44->44->23 MB, 45 MB goal, 4 P +gc 228 @137.205s 0%: 0+47+0 ms clock, 0+14/47/14+0 ms cpu, 44->45->23 MB, 46 MB goal, 4 P +gc 229 @137.850s 0%: 0+22+0.97 ms clock, 0+7.8/21/30+3.9 ms cpu, 44->45->22 MB, 46 MB goal, 4 P +gc 230 @138.558s 0%: 0+26+0 ms clock, 0+9.8/15/6.4+0 ms cpu, 44->45->22 MB, 45 MB goal, 4 P +gc 231 @139.151s 0%: 0+34+0 ms clock, 0+16/15/5.0+0 ms cpu, 44->44->22 MB, 45 MB goal, 4 P +gc 232 @139.984s 0%: 0+19+0 ms clock, 0+3.9/18/14+0 ms cpu, 43->44->23 MB, 45 MB goal, 4 P +gc 233 @140.946s 0%: 0+26+0 ms clock, 0+12/25/0.99+0 ms cpu, 46->46->23 MB, 47 MB goal, 4 P +gc 234 @141.905s 0%: 0+32+0 ms clock, 0+4.0/32/10+0 ms cpu, 45->45->23 MB, 46 MB goal, 4 P +gc 235 @142.819s 0%: 0+36+0 ms clock, 0+6.9/33/11+0 ms cpu, 46->46->22 MB, 47 MB goal, 4 P +gc 236 @143.760s 0%: 0+65+0.97 ms clock, 0+1.9/63/8.7+3.9 ms cpu, 43->44->22 MB, 45 MB goal, 4 P +gc 237 @144.650s 0%: 0+20+0 ms clock, 0+0.97/20/13+0 ms cpu, 44->45->22 MB, 45 MB goal, 4 P +gc 238 @145.428s 0%: 0+22+0 ms clock, 0+1.9/20/6.9+0 ms cpu, 43->44->22 MB, 45 MB goal, 4 P +gc 239 @146.192s 0%: 0+20+0 ms clock, 0+9.9/19/6.5+0 ms cpu, 44->45->23 MB, 45 MB goal, 4 P +gc 240 @146.893s 0%: 0+25+0 ms clock, 0+13/18/3.6+0 ms cpu, 45->46->23 MB, 47 MB goal, 4 P +gc 241 @147.507s 0%: 0+15+0 ms clock, 0+7.3/15/14+0 ms cpu, 45->46->23 MB, 46 MB goal, 4 P +gc 242 @148.639s 0%: 0+23+0 ms clock, 0+6.8/22/2.0+0 ms cpu, 45->46->23 MB, 47 MB goal, 4 P +gc 243 @149.226s 0%: 0+23+0 ms clock, 0+12/20/3.9+0 ms cpu, 45->45->24 MB, 46 MB goal, 4 P +scvg0: inuse: 49, idle: 3, sys: 52, released: 0, consumed: 52 (MB) +gc 244 @149.977s 0%: 0+28+0 ms clock, 0+2.8/26/6.7+0 ms cpu, 46->47->23 MB, 48 MB goal, 4 P +gc 245 @150.610s 0%: 0+23+0 ms clock, 0+1.9/23/9.8+0 ms cpu, 46->46->23 MB, 47 MB goal, 4 P +gc 246 @151.237s 0%: 0+27+0 ms clock, 0+6.8/27/3.9+0 ms cpu, 46->46->23 MB, 47 MB goal, 4 P +gc 247 @151.880s 0%: 0+19+0 ms clock, 0+10/19/2.0+0 ms cpu, 46->46->23 MB, 47 MB goal, 4 P +gc 248 @152.462s 0%: 0+27+0 ms clock, 0+3.8/26/4.8+0 ms cpu, 46->46->23 MB, 47 MB goal, 4 P +gc 249 @153.059s 0%: 0+22+0 ms clock, 0+9.6/21/2.0+0 ms cpu, 46->46->23 MB, 47 MB goal, 4 P +gc 250 @153.666s 0%: 0+19+0 ms clock, 0+13/19/3.9+0 ms cpu, 46->46->23 MB, 47 MB goal, 4 P +gc 251 @154.279s 0%: 0+21+0 ms clock, 0+3.9/19/14+0 ms cpu, 46->46->23 MB, 47 MB goal, 4 P +gc 252 @154.922s 0%: 0+22+0.97 ms clock, 0+14/13/5.9+3.9 ms cpu, 46->47->23 MB, 47 MB goal, 4 P +gc 253 @155.564s 0%: 0+21+0 ms clock, 0+12/21/6.8+0 ms cpu, 46->46->23 MB, 47 MB goal, 4 P +gc 254 @156.196s 0%: 0+19+0 ms clock, 0+14/19/3.9+0 ms cpu, 46->47->23 MB, 47 MB goal, 4 P +gc 255 @156.778s 0%: 0+22+0 ms clock, 0+14/15/4.9+0 ms cpu, 46->46->23 MB, 47 MB goal, 4 P +gc 256 @157.378s 0%: 0+21+0 ms clock, 0+7.8/13/12+0 ms cpu, 46->46->23 MB, 47 MB goal, 4 P +gc 257 @157.986s 0%: 0+31+0.45 ms clock, 0+8.8/18/1.8+1.8 ms cpu, 46->46->23 MB, 47 MB goal, 4 P +gc 258 @158.614s 0%: 0+16+0 ms clock, 0+7.8/14/12+0 ms cpu, 46->47->23 MB, 47 MB goal, 4 P +gc 259 @159.213s 0%: 0+19+0 ms clock, 0+14/17/1.8+0 ms cpu, 46->46->23 MB, 47 MB goal, 4 P +gc 260 @159.849s 0%: 0+24+0 ms clock, 0+4.9/21/7.7+0 ms cpu, 46->46->23 MB, 47 MB goal, 4 P +gc 261 @160.513s 0%: 0+30+0 ms clock, 0+14/15/3.9+0 ms cpu, 46->46->23 MB, 47 MB goal, 4 P +gc 262 @161.156s 0%: 0+25+0 ms clock, 0+18/14/3.9+0 ms cpu, 46->46->23 MB, 47 MB goal, 4 P +gc 263 @161.807s 0%: 0+21+0.46 ms clock, 0+11/19/5.7+1.8 ms cpu, 46->47->24 MB, 47 MB goal, 4 P +gc 264 @162.413s 0%: 0+24+0 ms clock, 0+7.5/23/3.9+0 ms cpu, 46->47->24 MB, 48 MB goal, 4 P +gc 265 @163.028s 0%: 0+30+0 ms clock, 0+12/13/4.0+0 ms cpu, 47->47->23 MB, 48 MB goal, 4 P +gc 266 @163.645s 0%: 0+21+0 ms clock, 0+8.8/19/4.8+0 ms cpu, 46->47->24 MB, 47 MB goal, 4 P +gc 267 @164.248s 0%: 0+11+0 ms clock, 0+1.9/11/23+0 ms cpu, 46->47->24 MB, 48 MB goal, 4 P +gc 268 @164.873s 0%: 0+24+0 ms clock, 0+15/14/8.8+0 ms cpu, 47->47->23 MB, 48 MB goal, 4 P +gc 269 @165.506s 0%: 0+19+0 ms clock, 0+7.8/19/3.8+0 ms cpu, 46->47->24 MB, 47 MB goal, 4 P +gc 270 @166.140s 0%: 0+27+0 ms clock, 0+8.8/21/3.9+0 ms cpu, 46->47->24 MB, 48 MB goal, 4 P +gc 271 @166.786s 0%: 0+32+0 ms clock, 0+12/16/1.9+0 ms cpu, 47->47->24 MB, 48 MB goal, 4 P +gc 272 @167.411s 0%: 0+23+0 ms clock, 0+12/13/6.9+0 ms cpu, 47->47->24 MB, 48 MB goal, 4 P +gc 273 @168.157s 0%: 0+15+0 ms clock, 0+5.9/7.8/21+0 ms cpu, 47->47->24 MB, 48 MB goal, 4 P +gc 274 @168.726s 0%: 0+27+0 ms clock, 0+0.45/25/5.4+0 ms cpu, 47->48->24 MB, 49 MB goal, 4 P +gc 275 @169.666s 0%: 0+25+0.93 ms clock, 0+15/13/5.9+3.7 ms cpu, 47->47->24 MB, 48 MB goal, 4 P +gc 276 @170.301s 0%: 0+22+0 ms clock, 0+6.7/22/3.9+0 ms cpu, 47->47->24 MB, 48 MB goal, 4 P +gc 277 @170.889s 0%: 0+25+0 ms clock, 0+4.8/23/6.7+0 ms cpu, 48->48->24 MB, 49 MB goal, 4 P +gc 278 @171.552s 0%: 0+30+0 ms clock, 0+16/14/3.9+0 ms cpu, 48->48->24 MB, 49 MB goal, 4 P +gc 279 @172.207s 0%: 0+17+0.97 ms clock, 0+8.8/13/11+3.9 ms cpu, 48->48->24 MB, 49 MB goal, 4 P +gc 280 @172.813s 0%: 0+18+0.46 ms clock, 0+8.8/17/14+1.8 ms cpu, 48->48->25 MB, 49 MB goal, 4 P +gc 281 @173.437s 0%: 0+18+0.94 ms clock, 0+10/18/4.4+3.7 ms cpu, 49->49->24 MB, 50 MB goal, 4 P +gc 282 @174.085s 0%: 0+32+0 ms clock, 0+8.8/20/2.0+0 ms cpu, 48->48->24 MB, 49 MB goal, 4 P +gc 283 @174.711s 0%: 0+22+0 ms clock, 0+14/21/1.9+0 ms cpu, 48->48->24 MB, 49 MB goal, 4 P +gc 284 @175.355s 0%: 0+11+0 ms clock, 0+2.9/10/23+0 ms cpu, 48->48->24 MB, 49 MB goal, 4 P +gc 285 @175.982s 0%: 0+11+0 ms clock, 0+3.8/10/22+0 ms cpu, 48->48->24 MB, 49 MB goal, 4 P +gc 286 @176.597s 0%: 0+28+0 ms clock, 0+0.97/27/3.9+0 ms cpu, 48->49->25 MB, 49 MB goal, 4 P +gc 287 @177.228s 0%: 0+27+0.99 ms clock, 0+0/24/19+3.9 ms cpu, 48->49->24 MB, 50 MB goal, 4 P +gc 288 @177.865s 0%: 0+18+0 ms clock, 0+14/17/7.8+0 ms cpu, 48->49->24 MB, 49 MB goal, 4 P +gc 289 @178.464s 0%: 0+15+0 ms clock, 0+9.7/14/11+0 ms cpu, 48->49->24 MB, 49 MB goal, 4 P +gc 290 @179.114s 0%: 0+31+0 ms clock, 0+0.94/31/7.2+0 ms cpu, 48->48->25 MB, 49 MB goal, 4 P +gc 291 @179.960s 0%: 0+20+0 ms clock, 0+10/18/4.0+0 ms cpu, 49->50->25 MB, 51 MB goal, 4 P +gc 292 @180.424s 0%: 0+13+0.45 ms clock, 0+3.9/13/25+1.8 ms cpu, 49->49->25 MB, 50 MB goal, 4 P +gc 293 @180.856s 0%: 0+23+0 ms clock, 0+13/11/9.6+0 ms cpu, 49->50->25 MB, 50 MB goal, 4 P +gc 294 @181.277s 0%: 0+14+0 ms clock, 0+8.7/12/20+0 ms cpu, 49->49->25 MB, 50 MB goal, 4 P +gc 295 @181.720s 0%: 0+15+0 ms clock, 0+11/14/12+0 ms cpu, 49->49->25 MB, 50 MB goal, 4 P +gc 296 @182.115s 0%: 0+27+0 ms clock, 0+4.0/26/6.8+0 ms cpu, 48->49->25 MB, 50 MB goal, 4 P +gc 297 @182.544s 0%: 0+24+0 ms clock, 0+16/6.5/9.1+0 ms cpu, 49->50->25 MB, 51 MB goal, 4 P +gc 298 @182.968s 0%: 0.97+27+0 ms clock, 3.9+6.8/27/0+0 ms cpu, 49->49->25 MB, 50 MB goal, 4 P +gc 299 @183.400s 0%: 0+15+0.94 ms clock, 0+6.7/15/18+3.7 ms cpu, 50->50->26 MB, 51 MB goal, 4 P +gc 300 @183.910s 0%: 0+20+0 ms clock, 0+5.0/20/9.8+0 ms cpu, 50->51->25 MB, 52 MB goal, 4 P +gc 301 @184.354s 0%: 0+26+0 ms clock, 0+3.9/25/8.6+0 ms cpu, 49->50->25 MB, 51 MB goal, 4 P +gc 302 @184.796s 0%: 0+14+0.96 ms clock, 0+8.7/13/15+3.8 ms cpu, 50->50->25 MB, 51 MB goal, 4 P +gc 303 @185.414s 0%: 0+19+0 ms clock, 0+6.8/16/15+0 ms cpu, 49->49->25 MB, 50 MB goal, 4 P +gc 304 @186.479s 0%: 0+13+0.96 ms clock, 0+4.8/9.7/24+3.8 ms cpu, 49->50->25 MB, 50 MB goal, 4 P +gc 305 @187.361s 0%: 0+21+0 ms clock, 0+0.97/21/13+0 ms cpu, 49->49->25 MB, 50 MB goal, 4 P +gc 306 @188.269s 0%: 0+23+0 ms clock, 0+5.8/15/12+0 ms cpu, 49->49->25 MB, 50 MB goal, 4 P +gc 307 @188.995s 0%: 0+16+0.93 ms clock, 0+1.9/16/33+3.7 ms cpu, 50->50->28 MB, 51 MB goal, 4 P +gc 308 @190.193s 0%: 0+13+0.96 ms clock, 0+3.9/12/21+3.8 ms cpu, 56->56->26 MB, 57 MB goal, 4 P +gc 309 @190.912s 0%: 0+24+0 ms clock, 0+14/20/9.7+0 ms cpu, 50->51->26 MB, 52 MB goal, 4 P +gc 310 @191.728s 0%: 0+21+0.95 ms clock, 0+10/20/6.9+3.8 ms cpu, 51->51->25 MB, 52 MB goal, 4 P +gc 311 @192.272s 0%: 0+20+0.92 ms clock, 0+8.8/19/5.8+3.7 ms cpu, 49->50->25 MB, 50 MB goal, 4 P +gc 312 @192.860s 0%: 0+17+0 ms clock, 0+0/13/20+0 ms cpu, 50->50->25 MB, 51 MB goal, 4 P +gc 313 @193.503s 0%: 0+12+0 ms clock, 0+0.97/12/25+0 ms cpu, 49->49->25 MB, 50 MB goal, 4 P +gc 314 @194.646s 0%: 0+23+0 ms clock, 0+5.9/22/7.9+0 ms cpu, 50->51->25 MB, 51 MB goal, 4 P +gc 315 @195.339s 0%: 0+22+0.93 ms clock, 0+6.3/22/11+3.7 ms cpu, 49->50->26 MB, 50 MB goal, 4 P +gc 316 @196.134s 0%: 0+25+0.97 ms clock, 0+11/25/1.0+3.9 ms cpu, 50->51->26 MB, 52 MB goal, 4 P +gc 317 @196.865s 0%: 0+34+0 ms clock, 0+9.7/25/12+0 ms cpu, 50->51->26 MB, 52 MB goal, 4 P +gc 318 @197.542s 0%: 0+21+0 ms clock, 0+9.3/20/4.5+0 ms cpu, 52->53->25 MB, 53 MB goal, 4 P +gc 319 @198.283s 0%: 0+19+0 ms clock, 0+8.8/19/5.8+0 ms cpu, 50->51->26 MB, 51 MB goal, 4 P +gc 320 @199.195s 0%: 0+26+0 ms clock, 0+5.8/26/0+0 ms cpu, 50->51->25 MB, 52 MB goal, 4 P +gc 321 @200.187s 0%: 0+22+0 ms clock, 0+8.7/22/5.8+0 ms cpu, 49->50->26 MB, 51 MB goal, 4 P +gc 322 @200.649s 0%: 0+36+0 ms clock, 0+12/25/7.8+0 ms cpu, 50->51->26 MB, 52 MB goal, 4 P +gc 323 @201.463s 0%: 0+31+0 ms clock, 0+15/16/7.7+0 ms cpu, 52->52->26 MB, 53 MB goal, 4 P +gc 324 @202.547s 0%: 0+22+0 ms clock, 0+13/22/2.9+0 ms cpu, 50->51->26 MB, 52 MB goal, 4 P +gc 325 @203.446s 0%: 0+14+0.96 ms clock, 0+4.4/14/15+3.8 ms cpu, 51->51->25 MB, 52 MB goal, 4 P +gc 326 @204.578s 0%: 0+22+0 ms clock, 0+0.50/19/20+0 ms cpu, 50->50->25 MB, 51 MB goal, 4 P +gc 327 @205.649s 0%: 0+24+0.49 ms clock, 0+0/21/11+1.9 ms cpu, 49->50->25 MB, 51 MB goal, 4 P +gc 328 @206.785s 0%: 0+17+0 ms clock, 0+0.93/16/33+0 ms cpu, 50->50->26 MB, 51 MB goal, 4 P +gc 329 @207.509s 0%: 0+17+0 ms clock, 0+3.9/17/17+0 ms cpu, 51->52->26 MB, 53 MB goal, 4 P +gc 330 @208.545s 0%: 0+21+0 ms clock, 0+9.8/21/4.8+0 ms cpu, 51->52->25 MB, 53 MB goal, 4 P +gc 331 @209.379s 0%: 0+20+0 ms clock, 0+11/20/5.8+0 ms cpu, 50->50->26 MB, 51 MB goal, 4 P +gc 332 @209.869s 0%: 0+24+0 ms clock, 0+5.3/23/5.9+0 ms cpu, 51->51->26 MB, 52 MB goal, 4 P +gc 333 @210.455s 0%: 0+18+0 ms clock, 0+6.0/17/12+0 ms cpu, 50->51->26 MB, 52 MB goal, 4 P +gc 334 @211.029s 0%: 0+25+0 ms clock, 0+4.9/22/5.8+0 ms cpu, 51->51->26 MB, 52 MB goal, 4 P +gc 335 @212.241s 0%: 0+28+0 ms clock, 0+0.97/27/4.8+0 ms cpu, 51->51->25 MB, 52 MB goal, 4 P +gc 336 @213.504s 0%: 0+22+0 ms clock, 0+10/21/3.9+0 ms cpu, 50->50->26 MB, 51 MB goal, 4 P +gc 337 @214.554s 0%: 0+11+0.50 ms clock, 0+1.0/11/27+2.0 ms cpu, 51->51->26 MB, 53 MB goal, 4 P +gc 338 @215.653s 0%: 0+24+0 ms clock, 0+3.9/24/3.9+0 ms cpu, 51->51->27 MB, 52 MB goal, 4 P +gc 339 @216.646s 0%: 0+24+0 ms clock, 0+3.3/24/2.9+0 ms cpu, 52->53->26 MB, 54 MB goal, 4 P +gc 340 @217.589s 0%: 0+14+0 ms clock, 0+0.94/14/21+0 ms cpu, 52->53->26 MB, 53 MB goal, 4 P +gc 341 @218.549s 0%: 0+13+0 ms clock, 0+8.7/13/16+0 ms cpu, 52->52->27 MB, 53 MB goal, 4 P +gc 342 @219.538s 0%: 0.97+27+0.94 ms clock, 3.9+12/10/7.9+3.7 ms cpu, 52->53->26 MB, 54 MB goal, 4 P +gc 343 @220.489s 0%: 0+26+0 ms clock, 0+2.8/26/5.9+0 ms cpu, 52->53->27 MB, 53 MB goal, 4 P +gc 344 @221.430s 0%: 0+18+0 ms clock, 0+7.7/17/9.7+0 ms cpu, 53->54->26 MB, 55 MB goal, 4 P +gc 345 @222.430s 0%: 0+28+0 ms clock, 0+2.9/27/3.0+0 ms cpu, 52->52->27 MB, 53 MB goal, 4 P +gc 346 @223.485s 0%: 0+24+0 ms clock, 0+5.3/21/6.9+0 ms cpu, 52->53->27 MB, 54 MB goal, 4 P +gc 347 @224.516s 0%: 0+15+0 ms clock, 0+0/10/26+0 ms cpu, 52->52->26 MB, 54 MB goal, 4 P +gc 348 @225.542s 0%: 0+19+0 ms clock, 0+6.9/16/9.8+0 ms cpu, 52->53->27 MB, 53 MB goal, 4 P +gc 349 @226.266s 0%: 0+24+0 ms clock, 0+11/23/0+0 ms cpu, 53->53->27 MB, 54 MB goal, 4 P +gc 350 @227.025s 0%: 0+23+0.92 ms clock, 0+5.9/18/10+3.7 ms cpu, 53->54->27 MB, 55 MB goal, 4 P +gc 351 @227.771s 0%: 0+21+0 ms clock, 0+11/21/5.8+0 ms cpu, 53->53->27 MB, 54 MB goal, 4 P +gc 352 @228.564s 0%: 0+24+0 ms clock, 0+13/23/0.92+0 ms cpu, 53->54->27 MB, 55 MB goal, 4 P +gc 353 @229.254s 0%: 0+14+0 ms clock, 0+0.97/12/26+0 ms cpu, 53->53->27 MB, 54 MB goal, 4 P +gc 354 @229.882s 0%: 0+14+0 ms clock, 0+6.3/14/16+0 ms cpu, 54->54->27 MB, 55 MB goal, 4 P +gc 355 @230.406s 0%: 0+19+1.0 ms clock, 0+6.9/18/10+4.0 ms cpu, 53->53->28 MB, 54 MB goal, 4 P +gc 356 @231.060s 0%: 0+23+0.45 ms clock, 0+10/23/1.9+1.8 ms cpu, 54->55->27 MB, 56 MB goal, 4 P +gc 357 @231.818s 0%: 0+23+0 ms clock, 0+6.8/21/6.7+0 ms cpu, 54->54->27 MB, 55 MB goal, 4 P +gc 358 @232.365s 0%: 0+29+0 ms clock, 0+5.4/21/5.8+0 ms cpu, 53->54->28 MB, 55 MB goal, 4 P +gc 359 @232.974s 0%: 0+21+0 ms clock, 0+9.4/20/9.4+0 ms cpu, 55->55->27 MB, 56 MB goal, 4 P +gc 360 @233.564s 0%: 0+27+0 ms clock, 0+9.3/19/5.7+0 ms cpu, 53->53->27 MB, 54 MB goal, 4 P +gc 361 @234.142s 0%: 0+28+0 ms clock, 0+13/15/5.9+0 ms cpu, 53->54->27 MB, 55 MB goal, 4 P +gc 362 @234.798s 0%: 0+18+0 ms clock, 0+7.8/18/10+0 ms cpu, 54->54->27 MB, 55 MB goal, 4 P +gc 363 @235.800s 0%: 0+14+0 ms clock, 0+5.8/12/22+0 ms cpu, 54->54->28 MB, 55 MB goal, 4 P +gc 364 @236.509s 0%: 0+22+0 ms clock, 0+4.8/21/8.7+0 ms cpu, 55->55->27 MB, 56 MB goal, 4 P +gc 365 @237.090s 0%: 0+21+0 ms clock, 0+8.7/20/7.4+0 ms cpu, 53->53->27 MB, 54 MB goal, 4 P +gc 366 @237.970s 0%: 0+15+0.45 ms clock, 0+4.9/15/20+1.8 ms cpu, 54->54->28 MB, 55 MB goal, 4 P +gc 367 @238.697s 0%: 0+22+0 ms clock, 0+8.7/22/0.98+0 ms cpu, 54->55->27 MB, 56 MB goal, 4 P +gc 368 @239.364s 0%: 0+21+0 ms clock, 0+0.97/21/15+0 ms cpu, 54->54->28 MB, 55 MB goal, 4 P +gc 369 @239.943s 0%: 0+18+0 ms clock, 0+11/18/10+0 ms cpu, 55->55->30 MB, 56 MB goal, 4 P +gc 370 @241.014s 0%: 0+20+0.97 ms clock, 0+9.7/18/5.8+3.8 ms cpu, 60->60->28 MB, 61 MB goal, 4 P +gc 371 @242.090s 0%: 0+20+0 ms clock, 0+8.3/19/6.0+0 ms cpu, 56->57->28 MB, 57 MB goal, 4 P +gc 372 @243.011s 0%: 0+23+0.97 ms clock, 0+3.8/12/19+3.9 ms cpu, 56->57->28 MB, 57 MB goal, 4 P +gc 373 @243.743s 0%: 0+31+0.96 ms clock, 0+15/12/4.8+3.8 ms cpu, 56->56->29 MB, 57 MB goal, 4 P +gc 374 @244.392s 0%: 0+26+0 ms clock, 0+10/25/6.4+0 ms cpu, 57->57->30 MB, 58 MB goal, 4 P +gc 375 @245.716s 0%: 0+26+0 ms clock, 0+5.8/24/7.7+0 ms cpu, 58->58->29 MB, 60 MB goal, 4 P +gc 376 @246.587s 0%: 0+16+0 ms clock, 0+2.0/16/18+0 ms cpu, 57->57->28 MB, 58 MB goal, 4 P +gc 377 @247.204s 0%: 0+31+0 ms clock, 0+1.0/31/2.0+0 ms cpu, 54->55->28 MB, 56 MB goal, 4 P +gc 378 @247.606s 0%: 0+20+0 ms clock, 0+2.8/20/33+0 ms cpu, 54->55->33 MB, 56 MB goal, 4 P +gc 379 @248.524s 0%: 0+21+0 ms clock, 0+13/20/20+0 ms cpu, 64->64->28 MB, 66 MB goal, 4 P +gc 380 @249.245s 0%: 0+25+0 ms clock, 0+17/9.7/8.7+0 ms cpu, 55->55->28 MB, 56 MB goal, 4 P +gc 381 @249.932s 0%: 0+20+0.92 ms clock, 0+16/19/6.7+3.7 ms cpu, 55->55->29 MB, 56 MB goal, 4 P +gc 382 @250.507s 0%: 0+23+0 ms clock, 0+6.8/22/3.9+0 ms cpu, 57->57->29 MB, 58 MB goal, 4 P +gc 383 @251.064s 0%: 0+24+1.0 ms clock, 0+7.8/23/0.96+4.0 ms cpu, 57->57->29 MB, 58 MB goal, 4 P +gc 384 @251.938s 0%: 0+25+0 ms clock, 0+5.8/24/6.9+0 ms cpu, 57->57->29 MB, 58 MB goal, 4 P +gc 385 @252.802s 0%: 0+23+0 ms clock, 0+9.2/23/4.0+0 ms cpu, 57->57->29 MB, 58 MB goal, 4 P +gc 386 @253.346s 0%: 0+21+0.97 ms clock, 0+7.8/20/9.7+3.8 ms cpu, 57->57->29 MB, 58 MB goal, 4 P +gc 387 @253.882s 0%: 0+16+0 ms clock, 0+6.8/16/21+0 ms cpu, 57->58->29 MB, 59 MB goal, 4 P +gc 388 @254.611s 0%: 0+21+0 ms clock, 0+7.7/19/11+0 ms cpu, 58->58->29 MB, 59 MB goal, 4 P +gc 389 @255.214s 0%: 0+16+0 ms clock, 0+1.9/14/22+0 ms cpu, 57->57->29 MB, 59 MB goal, 4 P +gc 390 @255.930s 0%: 0+21+0.94 ms clock, 0+10/20/7.7+3.7 ms cpu, 56->57->29 MB, 58 MB goal, 4 P +gc 391 @256.619s 0%: 0+24+0 ms clock, 0+11/23/1.9+0 ms cpu, 57->57->29 MB, 58 MB goal, 4 P +gc 392 @257.739s 0%: 0+24+0.93 ms clock, 0+9.8/23/7.8+3.7 ms cpu, 57->58->29 MB, 59 MB goal, 4 P +gc 393 @258.369s 0%: 0+23+0 ms clock, 0+15/23/0+0 ms cpu, 58->58->29 MB, 59 MB goal, 4 P +gc 394 @259.116s 0%: 0+25+0 ms clock, 0+7.9/23/10+0 ms cpu, 57->58->29 MB, 58 MB goal, 4 P +gc 395 @259.973s 0%: 0+32+0 ms clock, 0+11/17/8.8+0 ms cpu, 58->58->29 MB, 59 MB goal, 4 P +gc 396 @260.928s 0%: 0+20+0 ms clock, 0+5.8/16/16+0 ms cpu, 58->58->29 MB, 59 MB goal, 4 P +gc 397 @261.238s 0%: 0+17+0 ms clock, 0+14/14/28+0 ms cpu, 57->57->35 MB, 58 MB goal, 4 P +gc 398 @261.968s 0%: 0+30+0 ms clock, 0+10/19/5.9+0 ms cpu, 68->69->32 MB, 70 MB goal, 4 P +gc 399 @262.765s 0%: 0+24+0.93 ms clock, 0+8.8/20/10+3.7 ms cpu, 63->64->29 MB, 65 MB goal, 4 P +gc 400 @263.403s 0%: 0+23+0 ms clock, 0+12/22/4.4+0 ms cpu, 58->58->29 MB, 59 MB goal, 4 P +gc 401 @263.930s 0%: 0+22+0 ms clock, 0+12/12/15+0 ms cpu, 58->58->32 MB, 59 MB goal, 4 P +gc 402 @264.791s 0%: 0+19+0 ms clock, 0+6.5/18/10+0 ms cpu, 63->63->29 MB, 64 MB goal, 4 P +gc 403 @265.449s 0%: 0+22+0 ms clock, 0+11/22/4.9+0 ms cpu, 57->58->29 MB, 59 MB goal, 4 P +gc 404 @266.195s 0%: 0+21+0 ms clock, 0+10/21/10+0 ms cpu, 58->58->29 MB, 59 MB goal, 4 P +gc 405 @267.003s 0%: 0+23+0 ms clock, 0+10/22/4.9+0 ms cpu, 58->58->30 MB, 59 MB goal, 4 P +gc 406 @267.536s 0%: 0+21+0 ms clock, 0+12/18/6.8+0 ms cpu, 58->59->30 MB, 60 MB goal, 4 P +gc 407 @268.143s 0%: 0+16+0.99 ms clock, 0+7.7/15/14+3.9 ms cpu, 58->59->29 MB, 60 MB goal, 4 P +gc 408 @268.885s 0%: 0+15+0 ms clock, 0+5.7/13/19+0 ms cpu, 58->58->29 MB, 59 MB goal, 4 P +gc 409 @270.097s 0%: 0+29+0 ms clock, 0+7.4/18/10+0 ms cpu, 57->58->29 MB, 59 MB goal, 4 P +gc 410 @272.659s 0%: 0+28+0 ms clock, 0+9.4/28/0.50+0 ms cpu, 57->58->29 MB, 59 MB goal, 4 P +gc 411 @273.899s 0%: 0+39+0 ms clock, 0+24/38/5.0+0 ms cpu, 58->58->30 MB, 59 MB goal, 4 P +gc 412 @274.940s 0%: 0+56+0 ms clock, 0+18/30/2.5+0 ms cpu, 58->59->29 MB, 60 MB goal, 4 P +gc 413 @277.763s 0%: 0+19+0 ms clock, 0+2.4/19/37+0 ms cpu, 58->58->31 MB, 59 MB goal, 4 P +gc 414 @278.408s 0%: 0+15+0 ms clock, 0+10/13/15+0 ms cpu, 60->61->30 MB, 62 MB goal, 4 P +gc 415 @278.520s 0%: 0.97+15+0 ms clock, 3.8+12/14/20+0 ms cpu, 59->60->30 MB, 60 MB goal, 4 P +gc 416 @278.678s 0%: 0+12+0 ms clock, 0+7.8/10/19+0 ms cpu, 60->61->30 MB, 61 MB goal, 4 P +gc 417 @278.779s 0%: 0+19+0 ms clock, 0+15/18/4.9+0 ms cpu, 60->61->31 MB, 61 MB goal, 4 P +gc 418 @278.906s 0%: 0+14+0 ms clock, 0+13/14/16+0 ms cpu, 60->60->31 MB, 62 MB goal, 4 P +gc 419 @279.042s 0%: 0+23+0 ms clock, 0+23/8.8/8.4+0 ms cpu, 61->62->31 MB, 63 MB goal, 4 P +gc 420 @279.147s 0%: 0+17+0 ms clock, 0+12/9.7/13+0 ms cpu, 59->61->31 MB, 62 MB goal, 4 P +gc 421 @279.272s 0%: 0+24+0 ms clock, 0+15/12/6.8+0 ms cpu, 60->61->31 MB, 62 MB goal, 4 P +gc 422 @279.380s 0%: 0+13+0 ms clock, 0+10/13/13+0 ms cpu, 60->61->31 MB, 62 MB goal, 4 P +gc 423 @279.513s 0%: 0+30+0 ms clock, 0+22/8.8/3.9+0 ms cpu, 60->61->31 MB, 63 MB goal, 4 P +gc 424 @279.626s 0%: 0+28+0 ms clock, 0+2.9/28/2.9+0 ms cpu, 59->60->30 MB, 62 MB goal, 4 P +gc 425 @279.875s 0%: 0+26+0.94 ms clock, 0+5.7/25/3.9+3.7 ms cpu, 59->60->30 MB, 60 MB goal, 4 P +gc 426 @280.926s 0%: 0+21+0 ms clock, 0+0/20/12+0 ms cpu, 59->59->29 MB, 60 MB goal, 4 P +gc 427 @281.653s 0%: 0+20+0 ms clock, 0+5.8/18/10+0 ms cpu, 57->58->30 MB, 59 MB goal, 4 P +gc 428 @282.418s 0%: 0+29+0 ms clock, 0+15/14/17+0 ms cpu, 58->59->30 MB, 60 MB goal, 4 P +gc 429 @283.220s 0%: 0+18+0 ms clock, 0+5.8/18/20+0 ms cpu, 60->60->30 MB, 61 MB goal, 4 P +gc 430 @283.916s 0%: 0+23+0 ms clock, 0+9.4/23/7.9+0 ms cpu, 59->59->30 MB, 60 MB goal, 4 P +gc 431 @284.574s 0%: 0+21+0.94 ms clock, 0+8.8/21/5.8+3.7 ms cpu, 59->59->30 MB, 60 MB goal, 4 P +gc 432 @285.557s 0%: 0+21+0.93 ms clock, 0+11/21/5.8+3.7 ms cpu, 58->59->29 MB, 60 MB goal, 4 P +gc 433 @287.014s 0%: 0+25+0 ms clock, 0+0.51/25/13+0 ms cpu, 58->58->30 MB, 59 MB goal, 4 P +gc 434 @287.950s 0%: 0+20+1.0 ms clock, 0+9.2/19/7.9+4.0 ms cpu, 58->59->30 MB, 60 MB goal, 4 P +gc 435 @289.151s 0%: 0+22+0 ms clock, 0+2.9/21/17+0 ms cpu, 59->59->30 MB, 60 MB goal, 4 P +gc 436 @290.691s 0%: 0+29+0 ms clock, 0+4.8/25/4.9+0 ms cpu, 58->59->30 MB, 60 MB goal, 4 P +gc 437 @291.520s 0%: 0+24+0 ms clock, 0+4.9/24/7.8+0 ms cpu, 58->59->30 MB, 60 MB goal, 4 P +gc 438 @292.632s 0%: 0+21+0 ms clock, 0+0.95/21/16+0 ms cpu, 59->60->30 MB, 61 MB goal, 4 P +gc 439 @293.808s 0%: 0+13+0 ms clock, 0+2.8/13/24+0 ms cpu, 58->59->30 MB, 60 MB goal, 4 P +scvg1: inuse: 61, idle: 11, sys: 73, released: 0, consumed: 73 (MB) +gc 440 @302.915s 0%: 0+129+0.54 ms clock, 0+63/106/74+2.1 ms cpu, 59->60->31 MB, 61 MB goal, 4 P +gc 441 @304.863s 0%: 0+16+0 ms clock, 0+0/15/23+0 ms cpu, 61->61->30 MB, 62 MB goal, 4 P +gc 442 @305.900s 0%: 0+38+0 ms clock, 0+1.0/38/2.9+0 ms cpu, 59->59->30 MB, 61 MB goal, 4 P +gc 443 @307.308s 0%: 0+89+46 ms clock, 0+84/75/4.9+186 ms cpu, 61->61->33 MB, 62 MB goal, 4 P +gc 444 @308.950s 0%: 0+45+0 ms clock, 0+7.0/42/10+0 ms cpu, 64->65->30 MB, 66 MB goal, 4 P +gc 445 @309.864s 0%: 0+28+0 ms clock, 0+0.46/26/25+0 ms cpu, 60->60->31 MB, 61 MB goal, 4 P +gc 446 @311.401s 0%: 0+29+0 ms clock, 0+1.0/16/19+0 ms cpu, 60->61->30 MB, 62 MB goal, 4 P +gc 447 @312.077s 0%: 0+21+0 ms clock, 0+7.7/21/5.8+0 ms cpu, 60->60->30 MB, 61 MB goal, 4 P +gc 448 @313.430s 0%: 0+29+0 ms clock, 0+16/13/4.8+0 ms cpu, 59->60->31 MB, 61 MB goal, 4 P +gc 449 @314.090s 0%: 0+29+0 ms clock, 0+10/29/5.8+0 ms cpu, 60->60->31 MB, 62 MB goal, 4 P +gc 450 @314.802s 0%: 0+33+0 ms clock, 0+5.8/33/5.8+0 ms cpu, 61->61->31 MB, 62 MB goal, 4 P +gc 451 @316.116s 0%: 0+43+0 ms clock, 0+9.3/42/8.3+0 ms cpu, 61->61->30 MB, 62 MB goal, 4 P +gc 452 @317.261s 0%: 0+24+0 ms clock, 0+9.7/22/6.8+0 ms cpu, 60->60->31 MB, 61 MB goal, 4 P +gc 453 @318.757s 0%: 0+38+0 ms clock, 0+10/20/3.9+0 ms cpu, 60->61->31 MB, 62 MB goal, 4 P +gc 454 @320.499s 0%: 0+14+0.45 ms clock, 0+0/14/25+1.8 ms cpu, 60->60->30 MB, 62 MB goal, 4 P +gc 455 @321.550s 0%: 0+31+0 ms clock, 0+9.7/24/4.8+0 ms cpu, 60->60->31 MB, 61 MB goal, 4 P +gc 456 @322.403s 0%: 0+17+0 ms clock, 0+0.97/17/19+0 ms cpu, 60->61->31 MB, 62 MB goal, 4 P +gc 457 @323.462s 0%: 0+15+0 ms clock, 0+7.4/14/18+0 ms cpu, 60->61->31 MB, 62 MB goal, 4 P +gc 458 @324.499s 0%: 0+27+0.93 ms clock, 0+18/12/5.9+3.7 ms cpu, 60->60->31 MB, 62 MB goal, 4 P +gc 459 @325.546s 0%: 0+26+0.51 ms clock, 0+13/25/0+2.0 ms cpu, 61->61->31 MB, 62 MB goal, 4 P +gc 460 @326.408s 0%: 0+21+0 ms clock, 0+10/21/30+0 ms cpu, 61->62->36 MB, 63 MB goal, 4 P +gc 461 @327.300s 0%: 0+22+0 ms clock, 0+13/10/12+0 ms cpu, 73->73->34 MB, 74 MB goal, 4 P +gc 462 @327.868s 0%: 0+25+0 ms clock, 0+0.44/25/10+0 ms cpu, 65->65->31 MB, 68 MB goal, 4 P +gc 463 @328.947s 0%: 0+22+0 ms clock, 0+2.4/22/9.7+0 ms cpu, 61->62->31 MB, 63 MB goal, 4 P +gc 464 @330.125s 0%: 0+32+0 ms clock, 0+12/18/7.9+0 ms cpu, 61->61->32 MB, 63 MB goal, 4 P +gc 465 @331.123s 0%: 0.97+17+0 ms clock, 3.9+5.9/17/19+0 ms cpu, 62->63->32 MB, 64 MB goal, 4 P +gc 466 @331.953s 0%: 0+14+0 ms clock, 0+3.0/14/24+0 ms cpu, 63->63->31 MB, 64 MB goal, 4 P +gc 467 @333.057s 0%: 0+17+0 ms clock, 0+5.0/16/22+0 ms cpu, 61->62->32 MB, 63 MB goal, 4 P +gc 468 @333.968s 0%: 0+14+0 ms clock, 0+0.49/14/24+0 ms cpu, 63->63->32 MB, 65 MB goal, 4 P +gc 469 @334.892s 0%: 0+20+0 ms clock, 0+14/20/2.9+0 ms cpu, 62->63->31 MB, 64 MB goal, 4 P +gc 470 @335.588s 0%: 0+12+0.97 ms clock, 0+2.8/11/21+3.9 ms cpu, 62->62->32 MB, 63 MB goal, 4 P +gc 471 @336.172s 0%: 0+18+0 ms clock, 0+6.8/17/19+0 ms cpu, 63->64->33 MB, 65 MB goal, 4 P +gc 472 @337.279s 0%: 0+20+0 ms clock, 0+0.47/20/17+0 ms cpu, 65->66->32 MB, 67 MB goal, 4 P +gc 473 @339.230s 0%: 0+29+0 ms clock, 0+0.95/28/4.8+0 ms cpu, 62->63->31 MB, 64 MB goal, 4 P +gc 474 @341.432s 0%: 0+24+0 ms clock, 0+10/24/4.8+0 ms cpu, 62->62->32 MB, 63 MB goal, 4 P +gc 475 @342.431s 0%: 0+29+0 ms clock, 0+7.8/20/12+0 ms cpu, 63->64->33 MB, 65 MB goal, 4 P +gc 476 @343.500s 0%: 0+24+0 ms clock, 0+11/21/1.8+0 ms cpu, 64->65->32 MB, 66 MB goal, 4 P +gc 477 @344.278s 0%: 0+25+0 ms clock, 0+7.3/24/5.4+0 ms cpu, 63->63->32 MB, 64 MB goal, 4 P +gc 478 @345.226s 0%: 0+22+0 ms clock, 0+9.7/18/11+0 ms cpu, 62->63->32 MB, 64 MB goal, 4 P +gc 479 @345.873s 0%: 0+22+1.0 ms clock, 0+10/22/6.5+4.0 ms cpu, 63->63->32 MB, 65 MB goal, 4 P +gc 480 @346.518s 0%: 0+28+0 ms clock, 0+15/14/8.7+0 ms cpu, 63->64->32 MB, 65 MB goal, 4 P +gc 481 @347.221s 0%: 0+28+0 ms clock, 0+0/27/8.8+0 ms cpu, 64->64->33 MB, 65 MB goal, 4 P +gc 482 @348.053s 0%: 0+14+0.97 ms clock, 0+3.9/14/22+3.8 ms cpu, 65->65->33 MB, 66 MB goal, 4 P +gc 483 @348.957s 0%: 0+21+0.46 ms clock, 0+11/15/8.8+1.8 ms cpu, 65->65->32 MB, 67 MB goal, 4 P +gc 484 @349.695s 0%: 0+18+0 ms clock, 0+0/17/21+0 ms cpu, 64->64->32 MB, 65 MB goal, 4 P +gc 485 @350.506s 0%: 0+31+0 ms clock, 0+20/9.8/5.8+0 ms cpu, 63->64->32 MB, 65 MB goal, 4 P +gc 486 @351.088s 0%: 0+28+0 ms clock, 0+21/14/7.5+0 ms cpu, 63->64->32 MB, 65 MB goal, 4 P +gc 487 @351.850s 0%: 0+23+0.97 ms clock, 0+12/23/3.9+3.8 ms cpu, 63->64->32 MB, 65 MB goal, 4 P +gc 488 @352.861s 0%: 0+28+0 ms clock, 0+8.9/22/6.8+0 ms cpu, 64->64->33 MB, 65 MB goal, 4 P +gc 489 @354.052s 0%: 0+29+0 ms clock, 0+11/26/14+0 ms cpu, 64->64->32 MB, 66 MB goal, 4 P +gc 490 @355.201s 0%: 0+21+0.99 ms clock, 0+0.94/21/16+3.9 ms cpu, 64->64->33 MB, 65 MB goal, 4 P +gc 491 @356.094s 0%: 0+24+0.97 ms clock, 0+10/21/6.8+3.9 ms cpu, 64->64->33 MB, 66 MB goal, 4 P +gc 492 @356.932s 0%: 0+21+0 ms clock, 0+4.8/21/13+0 ms cpu, 64->65->32 MB, 66 MB goal, 4 P +gc 493 @357.910s 0%: 0+29+0 ms clock, 0+11/26/3.9+0 ms cpu, 64->64->32 MB, 65 MB goal, 4 P +gc 494 @359.075s 0%: 0+23+0 ms clock, 0+8.7/23/8.9+0 ms cpu, 63->64->33 MB, 65 MB goal, 4 P +gc 495 @360.701s 0%: 0+24+0 ms clock, 0+6.2/22/13+0 ms cpu, 64->65->33 MB, 66 MB goal, 4 P +gc 496 @362.061s 0%: 0+59+0.54 ms clock, 0+18/47/21+2.1 ms cpu, 64->64->33 MB, 66 MB goal, 4 P +gc 497 @363.195s 0%: 0+24+0 ms clock, 0+9.7/22/5.8+0 ms cpu, 65->66->32 MB, 67 MB goal, 4 P +gc 498 @363.926s 0%: 0+32+1.0 ms clock, 0+2.9/32/3.9+4.0 ms cpu, 64->64->33 MB, 65 MB goal, 4 P +gc 499 @364.649s 0%: 0+33+1.9 ms clock, 0+11/30/9.6+7.8 ms cpu, 64->65->34 MB, 66 MB goal, 4 P +gc 500 @365.817s 0%: 0+23+0.49 ms clock, 0+10/23/6.8+1.9 ms cpu, 66->67->32 MB, 68 MB goal, 4 P +gc 501 @367.519s 0%: 0+23+0 ms clock, 0+4.5/21/25+0 ms cpu, 64->64->33 MB, 65 MB goal, 4 P +gc 502 @369.152s 0%: 0+18+0 ms clock, 0+5.8/18/17+0 ms cpu, 65->66->33 MB, 67 MB goal, 4 P +gc 503 @370.341s 0%: 0+27+0 ms clock, 0+0/27/7.8+0 ms cpu, 65->65->33 MB, 67 MB goal, 4 P +gc 504 @371.542s 0%: 0+32+0.92 ms clock, 0+17/15/0+3.7 ms cpu, 64->65->33 MB, 66 MB goal, 4 P +gc 505 @372.629s 0%: 0+22+0 ms clock, 0+4.9/13/21+0 ms cpu, 65->65->33 MB, 66 MB goal, 4 P +gc 506 @373.574s 0%: 0+27+0 ms clock, 0+0/27/7.3+0 ms cpu, 65->65->33 MB, 67 MB goal, 4 P +gc 507 @374.441s 0%: 0+25+0 ms clock, 0+6.8/17/15+0 ms cpu, 65->66->33 MB, 67 MB goal, 4 P +gc 508 @375.768s 0%: 0+51+0.97 ms clock, 0+4.9/26/5.8+3.9 ms cpu, 66->66->34 MB, 67 MB goal, 4 P +gc 509 @376.727s 0%: 0+28+0.93 ms clock, 0+13/19/4.9+3.7 ms cpu, 67->67->33 MB, 69 MB goal, 4 P +gc 510 @377.914s 0%: 0+30+0 ms clock, 0+6.8/24/15+0 ms cpu, 65->66->33 MB, 67 MB goal, 4 P +gc 511 @379.131s 0%: 0+20+0 ms clock, 0+4.8/20/11+0 ms cpu, 66->66->34 MB, 67 MB goal, 4 P +gc 512 @380.489s 0%: 0+35+0 ms clock, 0+17/14/5.4+0 ms cpu, 66->66->34 MB, 68 MB goal, 4 P +gc 513 @381.724s 0%: 0+34+0 ms clock, 0+13/21/1.9+0 ms cpu, 66->66->34 MB, 68 MB goal, 4 P +gc 514 @383.020s 0%: 0+12+0 ms clock, 0+5.8/12/25+0 ms cpu, 66->66->34 MB, 68 MB goal, 4 P +gc 515 @383.905s 0%: 0+20+0 ms clock, 0+12/18/7.7+0 ms cpu, 66->67->34 MB, 68 MB goal, 4 P +gc 516 @385.063s 0%: 0+29+0 ms clock, 0+2.4/26/13+0 ms cpu, 66->67->34 MB, 68 MB goal, 4 P +gc 517 @386.468s 0%: 0+23+0 ms clock, 0+10/23/7.7+0 ms cpu, 67->67->34 MB, 69 MB goal, 4 P +gc 518 @387.436s 0%: 0+27+0 ms clock, 0+3.9/25/9.8+0 ms cpu, 67->67->34 MB, 69 MB goal, 4 P +gc 519 @388.294s 0%: 0+27+0 ms clock, 0+3.4/26/5.9+0 ms cpu, 67->68->34 MB, 69 MB goal, 4 P +gc 520 @389.380s 0%: 0+28+0.94 ms clock, 0+0/16/19+3.7 ms cpu, 68->68->34 MB, 69 MB goal, 4 P +gc 521 @389.992s 0%: 0+13+0.97 ms clock, 0+5.8/13/23+3.9 ms cpu, 67->67->35 MB, 69 MB goal, 4 P +gc 522 @390.888s 0%: 0+15+1.0 ms clock, 0+0.97/12/23+4.0 ms cpu, 68->69->34 MB, 70 MB goal, 4 P +gc 523 @392.615s 0%: 0+47+0 ms clock, 0+17/18/2.9+0 ms cpu, 67->68->35 MB, 69 MB goal, 4 P +gc 524 @393.800s 0%: 0+19+0 ms clock, 0+0.97/18/28+0 ms cpu, 68->69->36 MB, 70 MB goal, 4 P +gc 525 @394.996s 0%: 0+26+0.47 ms clock, 0+13/25/0.98+1.8 ms cpu, 70->71->35 MB, 72 MB goal, 4 P +gc 526 @396.467s 0%: 0+22+0 ms clock, 0+12/21/4.8+0 ms cpu, 68->68->35 MB, 70 MB goal, 4 P +gc 527 @397.854s 0%: 0+30+0 ms clock, 0+0/29/5.8+0 ms cpu, 68->68->35 MB, 70 MB goal, 4 P +gc 528 @399.470s 0%: 0+26+0.93 ms clock, 0+0/26/8.7+3.7 ms cpu, 68->68->35 MB, 70 MB goal, 4 P +gc 529 @400.979s 0%: 0+23+0.98 ms clock, 0+6.8/20/10+3.9 ms cpu, 68->68->35 MB, 70 MB goal, 4 P +gc 530 @401.985s 0%: 0+20+0 ms clock, 0+8.8/20/17+0 ms cpu, 68->69->35 MB, 70 MB goal, 4 P +gc 531 @402.724s 0%: 0+24+0 ms clock, 0+7.4/23/10+0 ms cpu, 69->69->36 MB, 71 MB goal, 4 P +gc 532 @403.903s 0%: 0+25+0.50 ms clock, 0+10/25/5.8+2.0 ms cpu, 71->71->35 MB, 73 MB goal, 4 P +gc 533 @404.896s 0%: 0+33+0 ms clock, 0+4.9/32/6.7+0 ms cpu, 69->69->36 MB, 71 MB goal, 4 P +gc 534 @406.105s 0%: 0+36+0 ms clock, 0+12/21/6.8+0 ms cpu, 71->71->35 MB, 72 MB goal, 4 P +gc 535 @407.110s 0%: 0+28+0 ms clock, 0+5.0/28/4.9+0 ms cpu, 69->69->35 MB, 71 MB goal, 4 P +gc 536 @408.628s 0%: 0+22+0 ms clock, 0+2.9/15/19+0 ms cpu, 69->70->35 MB, 71 MB goal, 4 P +gc 537 @410.154s 0%: 0+45+0 ms clock, 0+16/19/1.9+0 ms cpu, 69->69->35 MB, 71 MB goal, 4 P +gc 538 @411.590s 0%: 0+26+0 ms clock, 0+6.8/22/9.7+0 ms cpu, 69->70->35 MB, 71 MB goal, 4 P +gc 539 @413.082s 0%: 0+34+0 ms clock, 0+8.7/23/3.9+0 ms cpu, 69->70->35 MB, 71 MB goal, 4 P +gc 540 @414.606s 0%: 0+30+0.49 ms clock, 0+3.5/29/2.8+1.9 ms cpu, 69->69->35 MB, 70 MB goal, 4 P +gc 541 @416.216s 0%: 0+74+0 ms clock, 0+0/41/45+0 ms cpu, 69->69->35 MB, 71 MB goal, 4 P +gc 542 @418.109s 0%: 0+25+0.45 ms clock, 0+4.5/22/8.6+1.8 ms cpu, 69->70->35 MB, 71 MB goal, 4 P +gc 543 @419.628s 0%: 0+30+0 ms clock, 0+8.2/30/1.4+0 ms cpu, 69->69->35 MB, 71 MB goal, 4 P +gc 544 @420.998s 0%: 0+16+0 ms clock, 0+1.9/15/26+0 ms cpu, 69->69->35 MB, 71 MB goal, 4 P +gc 545 @422.447s 0%: 0+28+0 ms clock, 0+0.92/27/9.7+0 ms cpu, 69->69->35 MB, 70 MB goal, 4 P +gc 546 @423.972s 0%: 0+29+0 ms clock, 0+1.9/29/5.8+0 ms cpu, 69->69->35 MB, 71 MB goal, 4 P +gc 547 @425.375s 0%: 0+24+0 ms clock, 0+7.7/23/9.7+0 ms cpu, 69->69->36 MB, 71 MB goal, 4 P +gc 548 @427.074s 0%: 0+19+0.97 ms clock, 0+0/12/25+3.9 ms cpu, 70->70->35 MB, 72 MB goal, 4 P +gc 549 @428.257s 0%: 0+27+0 ms clock, 0+8.8/24/10+0 ms cpu, 69->70->36 MB, 71 MB goal, 4 P +gc 550 @429.489s 0%: 0.97+20+0 ms clock, 3.9+8.7/18/15+0 ms cpu, 72->72->36 MB, 73 MB goal, 4 P +gc 551 @430.545s 0%: 0+24+0 ms clock, 0+9.7/23/3.5+0 ms cpu, 70->71->36 MB, 72 MB goal, 4 P +gc 552 @431.881s 0%: 0+28+0 ms clock, 0+0.93/23/9.7+0 ms cpu, 70->70->35 MB, 72 MB goal, 4 P +gc 553 @433.061s 0%: 0+18+0 ms clock, 0+5.9/18/16+0 ms cpu, 70->70->36 MB, 71 MB goal, 4 P +gc 554 @433.987s 0%: 0+22+0.97 ms clock, 0+9.7/20/7.8+3.8 ms cpu, 70->71->36 MB, 72 MB goal, 4 P +gc 555 @435.309s 0%: 0+22+0 ms clock, 0+9.8/21/4.8+0 ms cpu, 70->71->36 MB, 72 MB goal, 4 P +gc 556 @436.386s 0%: 0+17+0 ms clock, 0+7.8/17/18+0 ms cpu, 70->71->36 MB, 72 MB goal, 4 P +gc 557 @437.288s 0%: 0+24+0 ms clock, 0+10/24/5.8+0 ms cpu, 71->71->36 MB, 73 MB goal, 4 P +gc 558 @438.067s 0%: 0+36+0 ms clock, 0+9.7/23/4.8+0 ms cpu, 71->71->36 MB, 73 MB goal, 4 P +gc 559 @438.880s 0%: 0+22+0.96 ms clock, 0+10/20/6.9+3.8 ms cpu, 71->72->36 MB, 73 MB goal, 4 P +gc 560 @439.488s 0%: 0+19+0 ms clock, 0+13/19/5.8+0 ms cpu, 71->72->36 MB, 73 MB goal, 4 P +gc 561 @440.871s 0%: 0+22+0 ms clock, 0+8.7/22/7.8+0 ms cpu, 72->72->36 MB, 73 MB goal, 4 P +gc 562 @442.487s 0%: 0+16+0 ms clock, 0+0/16/25+0 ms cpu, 71->71->37 MB, 73 MB goal, 4 P +gc 563 @444.132s 0%: 0+26+0 ms clock, 0+3.8/26/8.7+0 ms cpu, 72->72->37 MB, 74 MB goal, 4 P +gc 564 @445.273s 0%: 0+23+0 ms clock, 0+9.7/23/10+0 ms cpu, 73->74->37 MB, 75 MB goal, 4 P +gc 565 @446.411s 0%: 0+16+0 ms clock, 0+3.8/16/24+0 ms cpu, 73->73->37 MB, 75 MB goal, 4 P +gc 566 @447.507s 0%: 0+32+0.93 ms clock, 0+10/19/6.8+3.7 ms cpu, 72->73->37 MB, 74 MB goal, 4 P +gc 567 @449.265s 0%: 0+34+0 ms clock, 0+21/14/0+0 ms cpu, 72->72->37 MB, 74 MB goal, 4 P +scvg2: inuse: 64, idle: 16, sys: 80, released: 0, consumed: 80 (MB) +gc 568 @450.300s 0%: 0+28+0 ms clock, 0+9.8/21/7.8+0 ms cpu, 73->73->37 MB, 75 MB goal, 4 P +gc 569 @451.222s 0%: 0+43+0 ms clock, 0+0/43/4.8+0 ms cpu, 73->74->37 MB, 75 MB goal, 4 P +gc 570 @452.856s 0%: 0+35+0.98 ms clock, 0+13/22/0+3.9 ms cpu, 72->72->37 MB, 74 MB goal, 4 P +gc 571 @454.206s 0%: 0+23+0 ms clock, 0+9.6/23/5.8+0 ms cpu, 73->73->37 MB, 75 MB goal, 4 P +gc 572 @455.250s 0%: 0+28+0 ms clock, 0+7.8/24/6.8+0 ms cpu, 73->74->37 MB, 75 MB goal, 4 P +gc 573 @456.358s 0%: 0+31+0 ms clock, 0+1.0/29/8.8+0 ms cpu, 73->73->37 MB, 75 MB goal, 4 P +gc 574 @457.874s 0%: 0+34+0 ms clock, 0+12/18/5.9+0 ms cpu, 74->74->37 MB, 75 MB goal, 4 P +gc 575 @458.947s 0%: 0+27+0 ms clock, 0+0/17/24+0 ms cpu, 73->73->37 MB, 75 MB goal, 4 P +gc 576 @460.086s 0%: 0+17+0.97 ms clock, 0+0.54/17/25+3.9 ms cpu, 73->73->38 MB, 75 MB goal, 4 P +gc 577 @460.835s 0%: 0+33+0 ms clock, 0+0/33/9.7+0 ms cpu, 74->75->38 MB, 76 MB goal, 4 P +gc 578 @462.042s 0%: 0+22+1.0 ms clock, 0+3.8/20/15+4.0 ms cpu, 75->75->39 MB, 76 MB goal, 4 P +gc 579 @463.231s 0%: 0+26+0 ms clock, 0+13/25/2.9+0 ms cpu, 77->77->39 MB, 78 MB goal, 4 P +gc 580 @464.499s 0%: 0+18+0 ms clock, 0+6.8/17/17+0 ms cpu, 76->77->39 MB, 78 MB goal, 4 P +gc 581 @466.538s 0%: 0+34+0 ms clock, 0+12/18/10+0 ms cpu, 77->77->39 MB, 79 MB goal, 4 P +gc 582 @467.384s 0%: 0+30+0 ms clock, 0+10/19/5.9+0 ms cpu, 77->77->39 MB, 79 MB goal, 4 P +gc 583 @468.989s 0%: 0+27+0 ms clock, 0+9.2/25/10+0 ms cpu, 76->77->40 MB, 78 MB goal, 4 P +gc 584 @470.448s 0%: 0+30+0.92 ms clock, 0+5.8/30/1.9+3.7 ms cpu, 78->78->39 MB, 80 MB goal, 4 P +gc 585 @472.023s 0%: 0+32+0 ms clock, 0+1.8/29/8.7+0 ms cpu, 77->77->40 MB, 79 MB goal, 4 P +gc 586 @473.270s 0%: 0+27+0 ms clock, 0+5.3/27/7.7+0 ms cpu, 78->78->39 MB, 80 MB goal, 4 P +gc 587 @475.315s 0%: 0+29+0 ms clock, 0+4.8/29/6.8+0 ms cpu, 77->78->40 MB, 79 MB goal, 4 P +gc 588 @476.895s 0%: 0+21+0 ms clock, 0+1.4/20/24+0 ms cpu, 78->79->40 MB, 80 MB goal, 4 P +gc 589 @477.977s 0%: 0+27+0.97 ms clock, 0+11/27/0.98+3.9 ms cpu, 78->79->40 MB, 80 MB goal, 4 P +gc 590 @479.088s 0%: 0+32+0 ms clock, 0+5.4/32/7.5+0 ms cpu, 79->79->40 MB, 81 MB goal, 4 P +gc 591 @480.167s 0%: 0+17+0.95 ms clock, 0+2.9/17/25+3.8 ms cpu, 79->79->40 MB, 81 MB goal, 4 P +gc 592 @481.276s 0%: 0+42+0 ms clock, 0+9.2/27/3.9+0 ms cpu, 79->79->40 MB, 81 MB goal, 4 P +gc 593 @482.456s 0%: 0+25+1.0 ms clock, 0+10/25/11+4.0 ms cpu, 79->80->40 MB, 81 MB goal, 4 P +gc 594 @483.574s 0%: 0+23+0 ms clock, 0+11/23/7.8+0 ms cpu, 79->80->40 MB, 81 MB goal, 4 P +gc 595 @484.577s 0%: 0+27+0 ms clock, 0+5.9/23/12+0 ms cpu, 79->80->41 MB, 81 MB goal, 4 P +gc 596 @485.753s 0%: 0+23+0 ms clock, 0+12/23/5.9+0 ms cpu, 80->80->41 MB, 82 MB goal, 4 P +gc 597 @486.445s 0%: 0+30+0 ms clock, 0+5.8/29/9.8+0 ms cpu, 80->80->41 MB, 82 MB goal, 4 P +gc 598 @487.140s 0%: 0+29+0 ms clock, 0+2.8/28/10+0 ms cpu, 81->81->41 MB, 83 MB goal, 4 P +gc 599 @488.083s 0%: 0+30+1.0 ms clock, 0+11/30/8.7+4.0 ms cpu, 81->82->41 MB, 83 MB goal, 4 P +gc 600 @489.920s 0%: 0+25+0.93 ms clock, 0+8.8/25/7.8+3.7 ms cpu, 81->82->41 MB, 83 MB goal, 4 P +gc 601 @491.129s 0%: 0+31+0 ms clock, 0+3.9/31/5.8+0 ms cpu, 81->82->41 MB, 83 MB goal, 4 P +gc 602 @492.225s 0%: 0+25+1.0 ms clock, 0+9.8/25/4.9+4.0 ms cpu, 81->82->41 MB, 83 MB goal, 4 P +gc 603 @493.331s 0%: 0+15+0 ms clock, 0+7.8/15/23+0 ms cpu, 80->81->41 MB, 83 MB goal, 4 P +gc 604 @494.577s 0%: 0+30+0 ms clock, 0+7.7/24/8.7+0 ms cpu, 80->81->41 MB, 82 MB goal, 4 P +gc 605 @495.748s 0%: 0+35+0 ms clock, 0+9.7/21/6.7+0 ms cpu, 80->81->41 MB, 82 MB goal, 4 P +gc 606 @496.838s 0%: 0+27+0 ms clock, 0+9.7/26/5.8+0 ms cpu, 80->81->42 MB, 82 MB goal, 4 P +gc 607 @498.047s 0%: 0+39+0 ms clock, 0+18/17/1.9+0 ms cpu, 82->82->41 MB, 84 MB goal, 4 P +gc 608 @499.643s 0%: 0+19+0.92 ms clock, 0+8.7/18/15+3.7 ms cpu, 81->82->42 MB, 83 MB goal, 4 P +gc 609 @500.918s 0%: 0+23+0 ms clock, 0+5.8/22/13+0 ms cpu, 83->83->42 MB, 85 MB goal, 4 P +gc 610 @501.955s 0%: 0+26+0 ms clock, 0+19/24/1.9+0 ms cpu, 82->82->44 MB, 84 MB goal, 4 P +gc 611 @502.936s 0%: 0+33+0 ms clock, 0+1.9/33/4.8+0 ms cpu, 85->86->42 MB, 88 MB goal, 4 P +gc 612 @504.191s 0%: 0+22+0.45 ms clock, 0+14/21/8.7+1.8 ms cpu, 82->82->43 MB, 84 MB goal, 4 P +gc 613 @505.140s 0%: 0+35+0 ms clock, 0+15/22/10+0 ms cpu, 85->86->46 MB, 87 MB goal, 4 P +gc 614 @506.101s 0%: 0+31+0 ms clock, 0+1.9/30/11+0 ms cpu, 90->91->42 MB, 92 MB goal, 4 P +gc 615 @507.234s 0%: 0+29+0 ms clock, 0+21/14/8.8+0 ms cpu, 83->83->42 MB, 85 MB goal, 4 P +gc 616 @508.338s 0%: 0+26+0 ms clock, 0+9.7/23/15+0 ms cpu, 82->83->42 MB, 85 MB goal, 4 P +gc 617 @509.271s 0%: 0+32+0.93 ms clock, 0+0.97/32/4.9+3.7 ms cpu, 83->83->42 MB, 85 MB goal, 4 P +gc 618 @510.495s 0%: 0+23+0 ms clock, 0+0.50/23/20+0 ms cpu, 83->83->42 MB, 85 MB goal, 4 P +gc 619 @511.631s 0%: 0+30+0 ms clock, 0+3.8/29/7.7+0 ms cpu, 83->84->42 MB, 85 MB goal, 4 P +gc 620 @512.758s 0%: 0+33+0 ms clock, 0+8.7/32/3.9+0 ms cpu, 83->84->42 MB, 85 MB goal, 4 P +gc 621 @513.848s 0%: 0+24+0 ms clock, 0+2.9/24/16+0 ms cpu, 83->84->42 MB, 85 MB goal, 4 P +gc 622 @514.938s 0%: 0+29+0 ms clock, 0+5.8/29/6.8+0 ms cpu, 83->84->42 MB, 85 MB goal, 4 P +gc 623 @516.084s 0%: 0+31+0 ms clock, 0+20/9.8/15+0 ms cpu, 83->84->42 MB, 85 MB goal, 4 P +gc 624 @516.939s 0%: 0+22+0 ms clock, 0+12/15/19+0 ms cpu, 83->83->43 MB, 85 MB goal, 4 P +gc 625 @517.666s 0%: 0+24+0 ms clock, 0+18/7.8/15+0 ms cpu, 85->85->41 MB, 87 MB goal, 4 P +gc 626 @518.623s 0%: 0+22+0 ms clock, 0+14/10/20+0 ms cpu, 81->82->42 MB, 83 MB goal, 4 P +gc 627 @519.492s 0%: 0+27+0 ms clock, 0+4.4/26/11+0 ms cpu, 83->83->42 MB, 85 MB goal, 4 P +gc 628 @520.591s 0%: 0+24+0.45 ms clock, 0+10/24/5.8+1.8 ms cpu, 83->83->42 MB, 85 MB goal, 4 P +gc 629 @521.595s 0%: 0+21+1.0 ms clock, 0+0.97/20/25+4.0 ms cpu, 82->82->42 MB, 84 MB goal, 4 P +gc 630 @522.453s 0%: 0+23+0 ms clock, 0+9.8/23/10+0 ms cpu, 82->83->42 MB, 84 MB goal, 4 P +gc 631 @523.171s 0%: 0+20+0.97 ms clock, 0+7.7/20/20+3.8 ms cpu, 83->84->42 MB, 85 MB goal, 4 P +gc 632 @523.873s 0%: 0+30+0 ms clock, 0+3.9/29/7.9+0 ms cpu, 83->83->42 MB, 85 MB goal, 4 P +gc 633 @524.599s 0%: 0+32+0 ms clock, 0+7.9/23/6.8+0 ms cpu, 83->84->42 MB, 85 MB goal, 4 P +gc 634 @525.831s 0%: 0+18+0 ms clock, 0+0.49/18/25+0 ms cpu, 82->82->42 MB, 84 MB goal, 4 P +gc 635 @526.898s 0%: 0+26+0 ms clock, 0+14/12/14+0 ms cpu, 82->82->42 MB, 84 MB goal, 4 P