self compile problems with SSA simplifier

Stephen Weeks MLton@sourcelight.com
Wed, 14 Nov 2001 13:50:38 -0800


In order to try out a self compile, I made the CPS simplifier dead,
and built a version of MLton using an old stable version.  This worked
fine, and built something of about the right size (7.4M) in about the
right amount of time (520s).  The resulting executable however, is
miserably slow when attempting to self compile.  I've appended the log
below.  All of the pre-SSA passes are of the usual speed, but the SSA
optimizations are sometimes more than 100X too slow.  See,
e.g. removeUnused1.  The problem isn't too much live data, since GC%
is roughly normal.  Also, since it happens to all SSA passes, I'm
inclined to blame the shrinker, or some kind of property list
problem.  Anyways, I'm looking into it, but feel free to do so as
well.

--------------------------------------------------------------------------------
time mlton @MLton gc-messages gc-summary fixed-heap 475m -- -v2 -o mlton-compile mlton.cm
MLton starting
MLton VERSION (built Wed Nov 14 12:31:44 2001 on starlinux)
  created this file on Wed Nov 14 12:58:06 2001.
Do not edit this file.
Flag settings: 
   chunk: chunk per function
   debug: false
   defines: [NODEBUG,MLton_safe=TRUE,Exn_keepHistory=FALSE,MLton_detectOverflow=TRUE]
   detect overflow: true
   drop passes: []
   exn history: false
   fixed heap: None
   indentation: 3
   includes: [mlton.h]
   inline: NonRecursive {product = 320,small = 60}
   input file: mlton
   instrument: false
   instrument Sxml: false
   keepCps: false
   keepSSA: false
   keep diagnostics: []
   keep dot: false
   keep passes: []
   limit check per block: true
   new return: false
   native: true
   native commented: 0
   native live stack: false
   native optimize: 1
   native move hoist: true
   native copy prop: true
   native cutoff: 100
   native live transfer: 8
   native future: 64
   native ieee fp: false
   native split: Some (20000)
   optimize SSA: true
   polyvariance: Some ({rounds = 2,small = 30,product = 300})
   print at fun entry: false
   profile: false
   safe: true
   show types: false
   stack cont: false
   static: false
   type check: false
   use basis library: true
   verbosity: Pass
   Compile SML starting
      pre codegen starting
	 parseAndElaborate starting
/tmp/fileDSDaFe.67.S 25002: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 530
live(bytes): 19,035,028 (7.6%)
/tmp/fileDSDaFe.67.S 25002: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 900
live(bytes): 37,845,512 (15.2%)
/tmp/fileuHQjcZ.64.S 13914: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,270
live(bytes): 54,839,120 (22.0%)
	 parseAndElaborate finished in 5.68 + 2.70 (32% GC)
	 lex and parse totals 3.70 + 1.43 (28% GC)
	 elaborate totals 1.96 + 1.27 (39% GC)
	 dead starting
	 dead finished in 0.09 + 0.0 (0.0% GC)
	 gcc -DNODEBUG -DMLton_safe=TRUE -DExn_keepHistory=FALSE \
	     -DMLton_detectOverflow=TRUE -I/home/sweeks/mlton/include -o \
	     /tmp/fileHyHiZU /tmp/filetU1WR2.c -L/home/sweeks/mlton/lib \
	     -lmlton -lm -lgmp
	 /tmp/fileHyHiZU /tmp/filerWZGdO
	 infer starting
	    unification starting
/tmp/fileczChyd.26.S 2884: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,440
live(bytes): 54,651,784 (21.9%)
	    unification finished in 1.28 + 1.44 (53% GC)
	    finishInfer starting
/tmp/fileMR6nEf.29.S 3735: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,420
live(bytes): 62,458,776 (25.1%)
	    finishInfer finished in 1.56 + 1.42 (48% GC)
	 infer finished in 3.03 + 2.86 (49% GC)
	 inferSimplify starting
	 inferSimplify finished in 1.78 + 0.0 (0.0% GC)
	 mono starting
/tmp/fileclcTK5.11.S 4967: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 680
live(bytes): 32,143,500 (12.9%)
/tmp/fileclcTK5.11.S 4414: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 850
live(bytes): 40,126,796 (16.1%)
	 mono finished in 3.23 + 1.53 (32% GC)
	 monoSimplify starting
/tmp/filerQXs1e.65.S 5307: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,280
live(bytes): 63,178,232 (25.4%)
	 monoSimplify finished in 2.75 + 1.28 (32% GC)
	 implementExceptions starting
/tmp/file8zWNYR.9.S 21775: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,010
live(bytes): 49,608,672 (19.9%)
	 implementExceptions finished in 0.23 + 1.01 (81% GC)
	 implementExceptionsSimplify starting
	 implementExceptionsSimplify finished in 2.12 + 0.0 (0.0% GC)
	 polyvariance starting
/tmp/file8zWNYR.9.S 7602: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,180
live(bytes): 54,057,520 (21.7%)
/tmp/fileIrSjOR.59.S 20217: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,700
live(bytes): 135,423,528 (54.4%)
/tmp/file8zWNYR.9.S 8810: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,510
live(bytes): 57,229,940 (23.0%)
/tmp/filekbEv23.60.S 228: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,770
live(bytes): 79,137,996 (31.8%)
	 polyvariance finished in 7.92 + 8.16 (51% GC)
	 closureConvert starting
	    flow analysis starting
	    flow analysis finished in 0.98 + 0.0 (0.0% GC)
	    free variables starting
	    free variables finished in 0.70 + 0.0 (0.0% GC)
	    globalize starting
/tmp/fileczChyd.26.S 896: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,810
live(bytes): 86,305,424 (34.7%)
	    globalize finished in 0.46 + 2.81 (86% GC)
	    convert starting
/tmp/fileXQvCXA.58.S 13231: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,840
live(bytes): 117,427,524 (47.2%)
/tmp/fileDmNIjw.57.S 456: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,300
live(bytes): 143,332,944 (57.6%)
/tmp/fileT4ZEpI.56.S 14693: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,680
live(bytes): 162,593,672 (65.3%)
/tmp/fileXQvCXA.58.S 22714: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,970
live(bytes): 180,175,428 (72.3%)
/tmp/fileXQvCXA.58.S 13481: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 4,300
live(bytes): 192,892,796 (77.5%)
/tmp/fileDSDaFe.67.S 12044: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 4,310
live(bytes): 195,029,820 (78.3%)
/tmp/fileQzAfcj.6.S 9576: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,860
live(bytes): 190,086,092 (76.3%)
	    convert finished in 5.52 + 26.26 (83% GC)
	 closureConvert finished in 8.15 + 29.07 (78% GC)
	 closureConvertSimplify starting
	 closureConvertSimplify finished in 0.0 + 0.0 (0% GC)
	 toSSA starting
	    inferHandlers starting
	    inferHandlers finished in 0.57 + 0.0 (0.0% GC)
/tmp/fileQzAfcj.6.S 1435: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,780
live(bytes): 110,867,140 (44.5%)
	 toSSA finished in 1.40 + 2.78 (67% GC)
	 toSSASimplify starting
	    checkScopes starting
/tmp/filerQXs1e.65.S 5137: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,260
live(bytes): 109,798,576 (44.1%)
/tmp/filerQXs1e.65.S 5137: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,280
live(bytes): 126,109,320 (50.6%)
/tmp/filerQXs1e.65.S 5137: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,540
live(bytes): 140,891,212 (56.6%)
/tmp/filerQXs1e.65.S 4785: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,790
live(bytes): 154,172,080 (61.9%)
/tmp/filerQXs1e.65.S 5137: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,950
live(bytes): 165,991,720 (66.7%)
/tmp/filerQXs1e.65.S 5137: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,130
live(bytes): 175,906,640 (70.6%)
/tmp/filerQXs1e.65.S 4391: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,280
live(bytes): 184,963,480 (74.3%)
/tmp/filerQXs1e.65.S 5137: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,480
live(bytes): 192,730,428 (77.4%)
/tmp/fileDSDaFe.67.S 7488: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,620
live(bytes): 200,254,892 (80.4%)
	    checkScopes finished in 5.58 + 26.33 (83% GC)
/tmp/fileJOHGbQ.53.S 4825: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,740
live(bytes): 97,061,480 (39.0%)
	    removeUnused1 starting
/tmp/filehoVcT2.5.S 52384: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,800
live(bytes): 135,890,720 (54.6%)
/tmp/fileJWJIZg.44.S 16772: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,510
live(bytes): 160,544,996 (64.5%)
/tmp/fileJWJIZg.44.S 17025: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,090
live(bytes): 157,361,188 (63.2%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,120
live(bytes): 166,082,032 (66.7%)
/tmp/filedUrc7Q.45.S 5460: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,940
live(bytes): 166,758,956 (67.0%)
/tmp/fileDSDaFe.67.S 14356: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,960
live(bytes): 167,368,252 (67.2%)
/tmp/fileDSDaFe.67.S 15718: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,960
live(bytes): 168,088,096 (67.5%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,990
live(bytes): 168,883,200 (67.8%)
/tmp/fileDSDaFe.67.S 14356: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,010
live(bytes): 169,716,596 (68.1%)
/tmp/filehoVcT2.5.S 80822: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,020
live(bytes): 170,216,312 (68.3%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,020
live(bytes): 170,612,024 (68.5%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,090
live(bytes): 172,823,280 (69.4%)
/tmp/filegDexK3.68.S 14316: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,050
live(bytes): 172,279,020 (69.2%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,080
live(bytes): 172,652,020 (69.3%)
/tmp/filegDexK3.68.S 4811: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,080
live(bytes): 173,624,716 (69.7%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,120
live(bytes): 174,895,904 (70.2%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,110
live(bytes): 175,437,768 (70.4%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,130
live(bytes): 175,872,096 (70.6%)
/tmp/fileq7epm1.55.S 2333: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,140
live(bytes): 176,352,548 (70.8%)
/tmp/fileq7epm1.55.S 5554: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,240
live(bytes): 181,006,504 (72.7%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,210
live(bytes): 177,838,792 (71.4%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,190
live(bytes): 178,227,788 (71.6%)
/tmp/fileDSDaFe.67.S 14356: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,200
live(bytes): 178,650,888 (71.7%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,180
live(bytes): 179,425,140 (72.0%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,180
live(bytes): 179,797,460 (72.2%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,200
live(bytes): 180,289,076 (72.4%)
/tmp/fileDSDaFe.67.S 17693: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,180
live(bytes): 180,476,360 (72.5%)
/tmp/fileq7epm1.55.S 2709: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,240
live(bytes): 180,975,028 (72.7%)
/tmp/filegDexK3.68.S 14316: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,210
live(bytes): 181,112,676 (72.7%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,190
live(bytes): 181,571,136 (72.9%)
/tmp/fileq7epm1.55.S 2539: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,210
live(bytes): 182,302,132 (73.2%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,210
live(bytes): 182,982,284 (73.5%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,230
live(bytes): 182,878,712 (73.4%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,200
live(bytes): 183,203,324 (73.6%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,210
live(bytes): 183,683,264 (73.8%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,200
live(bytes): 183,367,960 (73.6%)
/tmp/fileq7epm1.55.S 8767: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,190
live(bytes): 183,323,676 (73.6%)
	    removeUnused1 finished in 454.48 + 115.89 (20% GC)
	    leafInline starting
/tmp/filegDexK3.68.S 15660: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,030
live(bytes): 92,836,408 (37.3%)
/tmp/fileq7epm1.55.S 9378: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,960
live(bytes): 96,521,292 (38.8%)
/tmp/fileV0BqRW.51.S 13328: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,060
live(bytes): 101,447,220 (40.7%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,170
live(bytes): 106,341,208 (42.7%)
/tmp/fileq7epm1.55.S 2452: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,290
live(bytes): 112,205,728 (45.1%)
/tmp/fileV0BqRW.51.S 10577: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,390
live(bytes): 115,734,796 (46.5%)
/tmp/fileDSDaFe.67.S 17693: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,450
live(bytes): 119,690,832 (48.1%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,550
live(bytes): 123,982,752 (49.8%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,700
live(bytes): 131,555,856 (52.8%)
/tmp/filegDexK3.68.S 4811: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,710
live(bytes): 132,041,028 (53.0%)
/tmp/fileDSDaFe.67.S 16473: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,790
live(bytes): 135,927,644 (54.6%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,870
live(bytes): 139,688,020 (56.1%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,940
live(bytes): 143,271,728 (57.5%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,010
live(bytes): 146,735,788 (58.9%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,090
live(bytes): 150,117,104 (60.3%)
/tmp/fileq7epm1.55.S 2591: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,150
live(bytes): 153,513,712 (61.6%)
/tmp/filegDexK3.68.S 15460: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,220
live(bytes): 156,376,216 (62.8%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,280
live(bytes): 159,357,000 (64.0%)
	    leafInline finished in 174.44 + 47.66 (21% GC)
	    contify1 starting
	       initReachCallerCallees starting
	       initReachCallerCallees finished in 0.13 + 0.0 (0.0% GC)
	       analyzeDom starting
		  buildGraph starting
		  buildGraph finished in 0.11 + 0.0 (0.0% GC)
		  computeDominators starting
/tmp/filerQXs1e.65.S 4726: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,690
live(bytes): 84,487,072 (33.9%)
		  computeDominators finished in 0.67 + 1.69 (72% GC)
		  compute ADom starting
		  compute ADom finished in 0.03 + 0.0 (0.0% GC)
	       analyzeDom finished in 0.89 + 1.69 (66% GC)
	       transform starting
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,640
live(bytes): 87,000,124 (34.9%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,670
live(bytes): 89,688,348 (36.0%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,740
live(bytes): 92,391,876 (37.1%)
/tmp/fileq7epm1.55.S 5142: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,800
live(bytes): 95,624,996 (38.4%)
/tmp/fileDSDaFe.67.S 15718: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,890
live(bytes): 98,454,904 (39.5%)
/tmp/fileq7epm1.55.S 5207: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,980
live(bytes): 101,246,640 (40.7%)
/tmp/fileq7epm1.55.S 8427: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,060
live(bytes): 104,770,356 (42.1%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,240
live(bytes): 109,241,800 (43.9%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,390
live(bytes): 117,102,940 (47.0%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,370
live(bytes): 115,423,836 (46.3%)
	       transform finished in 68.24 + 19.78 (22% GC)
	    contify1 finished in 69.40 + 21.47 (24% GC)
	    localFlatten1 starting
/tmp/fileDSDaFe.67.S 14356: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,450
live(bytes): 71,977,816 (28.9%)
/tmp/filegDexK3.68.S 14352: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,360
live(bytes): 74,083,796 (29.7%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,400
live(bytes): 76,382,008 (30.7%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,450
live(bytes): 78,528,072 (31.5%)
/tmp/fileFONEpw.52.S 12756: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,490
live(bytes): 80,726,064 (32.4%)
/tmp/fileFONEpw.52.S 12260: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,510
live(bytes): 82,911,500 (33.3%)
/tmp/fileDSDaFe.67.S 14356: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,550
live(bytes): 85,111,284 (34.2%)
/tmp/fileq7epm1.55.S 9378: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,610
live(bytes): 88,192,904 (35.4%)
/tmp/fileDSDaFe.67.S 16302: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,620
live(bytes): 88,955,188 (35.7%)
/tmp/fileDSDaFe.67.S 17693: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,690
live(bytes): 90,867,040 (36.5%)
	    localFlatten1 finished in 83.41 + 15.13 (15% GC)
	    constantPropagation starting
	       fixed point starting
	       fixed point finished in 1.49 + 0.0 (0.0% GC)
/tmp/fileyXcV6p.54.S 1144: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 4,420
live(bytes): 184,551,392 (74.1%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,560
live(bytes): 176,508,496 (70.9%)
/tmp/fileDSDaFe.67.S 16391: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,520
live(bytes): 177,539,256 (71.3%)
/tmp/fileDSDaFe.67.S 14356: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,550
live(bytes): 179,307,344 (72.0%)
/tmp/fileDSDaFe.67.S 12856: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,590
live(bytes): 180,238,868 (72.4%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,650
live(bytes): 182,203,356 (73.2%)
/tmp/fileDSDaFe.67.S 15718: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,720
live(bytes): 183,742,756 (73.8%)
/tmp/fileDSDaFe.67.S 15718: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,730
live(bytes): 184,951,216 (74.3%)
/tmp/fileq7epm1.55.S 8767: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,730
live(bytes): 185,487,476 (74.5%)
/tmp/fileq7epm1.55.S 3824: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,780
live(bytes): 186,899,564 (75.0%)
/tmp/fileDSDaFe.67.S 12148: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,790
live(bytes): 187,778,336 (75.4%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,840
live(bytes): 189,107,016 (75.9%)
/tmp/fileDSDaFe.67.S 14356: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,960
live(bytes): 192,898,268 (77.5%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,880
live(bytes): 191,257,336 (76.8%)
/tmp/fileDSDaFe.67.S 14356: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,890
live(bytes): 192,334,400 (77.2%)
/tmp/fileDSDaFe.67.S 12856: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,870
live(bytes): 193,444,612 (77.7%)
/tmp/fileDSDaFe.67.S 16302: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,870
live(bytes): 194,472,060 (78.1%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,940
live(bytes): 197,770,216 (79.4%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,910
live(bytes): 196,461,452 (78.9%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,940
live(bytes): 198,792,680 (79.8%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,920
live(bytes): 198,720,128 (79.8%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,890
live(bytes): 199,177,680 (80.0%)
/tmp/fileDSDaFe.67.S 17693: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,870
live(bytes): 200,022,060 (80.3%)
	    constantPropagation finished in 83.47 + 87.82 (51% GC)
	    useless starting
/tmp/filerQXs1e.65.S 553: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,630
live(bytes): 78,466,132 (31.5%)
/tmp/filesdEn4b.43.S 18896: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,580
live(bytes): 123,017,848 (49.4%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,600
live(bytes): 135,637,288 (54.5%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,550
live(bytes): 140,738,788 (56.5%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,660
live(bytes): 145,197,080 (58.3%)
/tmp/filehoVcT2.5.S 62555: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,710
live(bytes): 147,065,840 (59.1%)
/tmp/fileq7epm1.55.S 6855: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,840
live(bytes): 150,936,164 (60.6%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,960
live(bytes): 156,826,504 (63.0%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,970
live(bytes): 158,191,716 (63.5%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,010
live(bytes): 161,493,940 (64.8%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,120
live(bytes): 164,983,360 (66.2%)
/tmp/fileDSDaFe.67.S 14356: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,160
live(bytes): 168,166,792 (67.5%)
/tmp/fileDSDaFe.67.S 14356: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,230
live(bytes): 171,082,480 (68.7%)
/tmp/filehoVcT2.5.S 62868: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,260
live(bytes): 173,782,016 (69.8%)
/tmp/fileV0BqRW.51.S 12952: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,340
live(bytes): 177,732,612 (71.4%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,480
live(bytes): 184,907,724 (74.2%)
/tmp/fileDSDaFe.67.S 14356: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,410
live(bytes): 182,480,612 (73.3%)
/tmp/fileq7epm1.55.S 6346: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,510
live(bytes): 187,922,904 (75.5%)
	    useless finished in 73.97 + 53.02 (42% GC)
	    removeUnused2 starting
/tmp/fileJWJIZg.44.S 19168: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,760
live(bytes): 77,768,948 (31.2%)
/tmp/filegDexK3.68.S 15460: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,670
live(bytes): 77,553,080 (31.1%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,510
live(bytes): 80,809,264 (32.4%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,560
live(bytes): 83,711,348 (33.6%)
/tmp/fileq7epm1.55.S 6225: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,630
live(bytes): 86,865,408 (34.9%)
/tmp/fileDSDaFe.67.S 12856: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,690
live(bytes): 89,956,580 (36.1%)
/tmp/fileDSDaFe.67.S 17693: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,780
live(bytes): 93,970,316 (37.7%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,790
live(bytes): 96,152,172 (38.6%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,840
live(bytes): 99,252,300 (39.9%)
	    removeUnused2 finished in 73.46 + 15.23 (17% GC)
	    simplifyTypes starting
/tmp/filegDexK3.68.S 15460: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,120
live(bytes): 57,013,784 (22.9%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,320
live(bytes): 68,110,684 (27.3%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,520
live(bytes): 78,431,840 (31.5%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,710
live(bytes): 88,136,084 (35.4%)
/tmp/fileDSDaFe.67.S 15718: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,920
live(bytes): 97,965,536 (39.3%)
/tmp/fileJWJIZg.44.S 9396: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,090
live(bytes): 106,658,316 (42.8%)
/tmp/fileDSDaFe.67.S 17693: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,260
live(bytes): 115,838,072 (46.5%)
/tmp/fileq7epm1.55.S 2591: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,560
live(bytes): 130,927,844 (52.6%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,520
live(bytes): 130,144,908 (52.3%)
	    simplifyTypes finished in 74.63 + 17.02 (19% GC)
	    polyEqual starting
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 990
live(bytes): 53,258,028 (21.4%)
/tmp/fileq7epm1.55.S 2333: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,020
live(bytes): 56,522,228 (22.7%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,080
live(bytes): 59,756,560 (24.0%)
/tmp/fileDSDaFe.67.S 16302: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,140
live(bytes): 63,024,968 (25.3%)
/tmp/fileDSDaFe.67.S 17693: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,210
live(bytes): 66,410,772 (26.7%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,280
live(bytes): 70,595,804 (28.3%)
	    polyEqual finished in 70.44 + 6.72 (9% GC)
	    contify2 starting
	       initReachCallerCallees starting
/tmp/filehoVcT2.5.S 55031: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 970
live(bytes): 52,509,380 (21.1%)
	       initReachCallerCallees finished in 0.08 + 0.97 (92% GC)
	       analyzeDom starting
		  buildGraph starting
		  buildGraph finished in 0.08 + 0.0 (0.0% GC)
		  computeDominators starting
		  computeDominators finished in 0.49 + 0.0 (0.0% GC)
		  compute ADom starting
		  compute ADom finished in 0.01 + 0.0 (0.0% GC)
	       analyzeDom finished in 0.64 + 0.0 (0.0% GC)
	       transform starting
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,050
live(bytes): 57,047,588 (22.9%)
/tmp/fileDSDaFe.67.S 14356: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,140
live(bytes): 62,177,260 (25.0%)
/tmp/fileq7epm1.55.S 2333: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,190
live(bytes): 64,097,540 (25.7%)
/tmp/fileq7epm1.55.S 10144: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,240
live(bytes): 67,115,956 (27.0%)
/tmp/fileDSDaFe.67.S 14356: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,280
live(bytes): 70,393,888 (28.3%)
/tmp/fileFONEpw.52.S 12397: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,330
live(bytes): 73,438,344 (29.5%)
	       transform finished in 67.09 + 7.23 (10% GC)
	    contify2 finished in 67.91 + 8.20 (11% GC)
	    inline starting
/tmp/fileDSDaFe.67.S 15876: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,140
live(bytes): 53,758,892 (21.6%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,310
live(bytes): 62,296,032 (25.0%)
/tmp/filecT0VmH.63.S 2904: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,490
live(bytes): 70,227,700 (28.2%)
/tmp/fileFONEpw.52.S 12699: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,630
live(bytes): 77,411,760 (31.1%)
/tmp/fileDSDaFe.67.S 16302: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,760
live(bytes): 84,328,336 (33.9%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,970
live(bytes): 94,052,812 (37.8%)
/tmp/fileDSDaFe.67.S 14356: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,050
live(bytes): 97,842,056 (39.3%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,180
live(bytes): 104,869,488 (42.1%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,280
live(bytes): 109,868,300 (44.1%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,430
live(bytes): 116,414,284 (46.7%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,570
live(bytes): 123,780,980 (49.7%)
/tmp/fileDSDaFe.67.S 15945: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,630
live(bytes): 126,672,596 (50.9%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,750
live(bytes): 131,816,916 (52.9%)
/tmp/fileq7epm1.55.S 8615: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,870
live(bytes): 137,464,732 (55.2%)
/tmp/fileq7epm1.55.S 2333: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,230
live(bytes): 157,454,588 (63.2%)
/tmp/fileDSDaFe.67.S 15718: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,010
live(bytes): 145,368,368 (58.4%)
/tmp/filegDexK3.68.S 14316: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,190
live(bytes): 152,976,740 (61.4%)
/tmp/filegDexK3.68.S 15460: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,380
live(bytes): 164,587,672 (66.1%)
	    inline finished in 89.70 + 41.87 (32% GC)
	    localFlatten2 starting
/tmp/fileFONEpw.52.S 10148: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,460
live(bytes): 70,665,636 (28.4%)
/tmp/fileyXcV6p.54.S 18005: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,450
live(bytes): 74,881,376 (30.1%)
/tmp/filegDexK3.68.S 15460: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,440
live(bytes): 75,688,144 (30.4%)
/tmp/fileDSDaFe.67.S 15969: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,520
live(bytes): 78,414,348 (31.5%)
/tmp/fileDSDaFe.67.S 14356: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,640
live(bytes): 83,449,652 (33.5%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,650
live(bytes): 84,182,108 (33.8%)
/tmp/filegDexK3.68.S 15460: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,680
live(bytes): 86,629,788 (34.8%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,770
live(bytes): 89,374,840 (35.9%)
/tmp/fileDSDaFe.67.S 14356: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,760
live(bytes): 91,925,640 (36.9%)
/tmp/fileDSDaFe.67.S 14356: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,820
live(bytes): 94,575,024 (38.0%)
/tmp/filegDexK3.68.S 15460: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,890
live(bytes): 97,793,868 (39.3%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,900
live(bytes): 99,701,228 (40.0%)
	    localFlatten2 finished in 61.77 + 19.98 (24% GC)
	    removeUnused3 starting
/tmp/fileJWJIZg.44.S 18570: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,150
live(bytes): 132,026,820 (53.0%)
/tmp/fileq7epm1.55.S 4668: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,850
live(bytes): 129,248,296 (51.9%)
/tmp/fileq7epm1.55.S 5738: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,600
live(bytes): 131,613,784 (52.8%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,650
live(bytes): 133,964,140 (53.8%)
/tmp/fileDSDaFe.67.S 14356: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,690
live(bytes): 136,336,836 (54.7%)
/tmp/fileDSDaFe.67.S 16302: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,760
live(bytes): 138,621,904 (55.7%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,860
live(bytes): 140,648,728 (56.5%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,890
live(bytes): 142,924,852 (57.4%)
/tmp/fileFONEpw.52.S 12397: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,920
live(bytes): 145,069,964 (58.3%)
/tmp/fileDSDaFe.67.S 14356: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,970
live(bytes): 147,418,392 (59.2%)
/tmp/fileDSDaFe.67.S 12856: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,990
live(bytes): 148,977,780 (59.8%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,050
live(bytes): 151,574,572 (60.9%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,080
live(bytes): 153,408,408 (61.6%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,120
live(bytes): 155,380,252 (62.4%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,140
live(bytes): 157,238,160 (63.1%)
/tmp/fileFONEpw.52.S 12148: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,180
live(bytes): 159,251,956 (63.9%)
/tmp/filecT0VmH.63.S 2904: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,390
live(bytes): 170,802,680 (68.6%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,250
live(bytes): 162,782,164 (65.4%)
/tmp/fileq7epm1.55.S 3890: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,350
live(bytes): 167,933,236 (67.4%)
/tmp/fileDSDaFe.67.S 17716: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 3,370
live(bytes): 168,344,400 (67.6%)
	    removeUnused3 finished in 75.27 + 60.26 (44% GC)
	    contify3 starting
	       initReachCallerCallees starting
	       initReachCallerCallees finished in 0.07 + 0.0 (0.0% GC)
	       analyzeDom starting
		  buildGraph starting
		  buildGraph finished in 0.07 + 0.0 (0.0% GC)
		  computeDominators starting
/tmp/filerQXs1e.65.S 5137: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,560
live(bytes): 77,120,900 (31.0%)
		  computeDominators finished in 0.34 + 1.56 (82% GC)
		  compute ADom starting
		  compute ADom finished in 0.01 + 0.0 (0.0% GC)
	       analyzeDom finished in 0.45 + 1.56 (78% GC)
	       transform starting
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,420
live(bytes): 73,712,628 (29.6%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,480
live(bytes): 76,840,396 (30.9%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,550
live(bytes): 80,124,056 (32.2%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,670
live(bytes): 85,901,868 (34.5%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,690
live(bytes): 86,033,240 (34.5%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,720
live(bytes): 88,404,340 (35.5%)
/tmp/fileDSDaFe.67.S 16427: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,800
live(bytes): 92,312,476 (37.1%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,820
live(bytes): 94,304,888 (37.9%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,920
live(bytes): 98,442,196 (39.5%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,920
live(bytes): 99,963,868 (40.1%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,020
live(bytes): 105,499,944 (42.4%)
	       transform finished in 55.90 + 19.01 (25% GC)
	    contify3 finished in 56.55 + 20.57 (27% GC)
	    introduceLoops starting
	    introduceLoops finished in 0.03 + 0.0 (0.0% GC)
	    loopInvariant starting
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,370
live(bytes): 71,127,308 (28.6%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,580
live(bytes): 83,686,624 (33.6%)
/tmp/fileq7epm1.55.S 9378: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,450
live(bytes): 75,681,176 (30.4%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,570
live(bytes): 80,169,828 (32.2%)
/tmp/fileq7epm1.55.S 9789: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,620
live(bytes): 82,092,556 (33.0%)
/tmp/fileDSDaFe.67.S 16455: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,640
live(bytes): 83,989,292 (33.7%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,670
live(bytes): 86,146,508 (34.6%)
/tmp/fileDSDaFe.67.S 14356: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,760
live(bytes): 88,779,480 (35.6%)
/tmp/fileDSDaFe.67.S 14356: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,790
live(bytes): 92,989,948 (37.3%)
/tmp/fileq7epm1.55.S 10144: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,810
live(bytes): 93,602,684 (37.6%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,840
live(bytes): 96,237,680 (38.6%)
	    loopInvariant finished in 56.46 + 18.10 (24% GC)
	    flatten starting
/tmp/fileFONEpw.52.S 12397: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,190
live(bytes): 106,533,920 (42.8%)
/tmp/filegDexK3.68.S 15660: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,190
live(bytes): 110,694,628 (44.4%)
/tmp/fileq7epm1.55.S 3001: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,210
live(bytes): 111,734,340 (44.9%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,230
live(bytes): 113,203,996 (45.5%)
/tmp/fileDSDaFe.67.S 14356: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,330
live(bytes): 115,100,188 (46.2%)
/tmp/fileV0BqRW.51.S 12952: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,350
live(bytes): 116,989,196 (47.0%)
/tmp/fileDSDaFe.67.S 14356: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,390
live(bytes): 118,812,048 (47.7%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,480
live(bytes): 123,529,764 (49.6%)
/tmp/fileDSDaFe.67.S 16391: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,550
live(bytes): 127,153,544 (51.1%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,570
live(bytes): 128,268,144 (51.5%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,580
live(bytes): 129,601,092 (52.0%)
/tmp/filegDexK3.68.S 15460: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,630
live(bytes): 131,797,924 (52.9%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,650
live(bytes): 133,652,288 (53.7%)
/tmp/filegDexK3.68.S 4418: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,640
live(bytes): 134,008,952 (53.8%)
/tmp/fileDSDaFe.67.S 16473: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,870
live(bytes): 146,588,612 (58.9%)
/tmp/fileDSDaFe.67.S 16473: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,760
live(bytes): 139,917,976 (56.2%)
/tmp/fileDSDaFe.67.S 14356: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,770
live(bytes): 140,761,292 (56.5%)
	    flatten finished in 82.93 + 42.39 (34% GC)
	    localFlatten3 starting
/tmp/filegDexK3.68.S 4811: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,320
live(bytes): 69,084,800 (27.7%)
/tmp/fileV0BqRW.51.S 15292: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,400
live(bytes): 73,410,072 (29.5%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,440
live(bytes): 75,020,044 (30.1%)
/tmp/fileq7epm1.55.S 10144: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,530
live(bytes): 78,739,124 (31.6%)
/tmp/fileuHQjcZ.64.S 12445: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,580
live(bytes): 80,558,020 (32.3%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,660
live(bytes): 84,725,824 (34.0%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,700
live(bytes): 87,262,996 (35.0%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,760
live(bytes): 90,000,576 (36.1%)
/tmp/fileDSDaFe.67.S 14338: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,770
live(bytes): 92,717,732 (37.2%)
/tmp/fileDSDaFe.67.S 14356: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,830
live(bytes): 95,361,988 (38.3%)
/tmp/fileFONEpw.52.S 12260: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,860
live(bytes): 97,511,392 (39.2%)
/tmp/filegDexK3.68.S 14352: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,960
live(bytes): 102,559,428 (41.2%)
	    localFlatten3 finished in 65.24 + 19.81 (23% GC)
	    commonSubexp starting
/tmp/fileq7epm1.55.S 8948: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,960
live(bytes): 102,919,384 (41.3%)
/tmp/fileDSDaFe.67.S 14374: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 1,980
live(bytes): 102,965,248 (41.3%)
/tmp/fileq7epm1.55.S 8427: GC
Starting gc.
fromSpace 249,036,800  toSpace 249,036,800
Finished gc.
time(ms): 2,020
live(bytes): 105,159,724 (42.2%)
	    commonSubexp raised in 21.43 + 5.96 (22% GC)
	 toSSASimplify raised in 1742.38 + 645.17 (27% GC)
      pre codegen raised in 1779.26 + 694.56 (28% GC)
   Compile SML raised in 1779.26 + 694.56 (28% GC)
MLton raised in 1779.35 + 694.56 (28% GC)
mlton: unhandled exception: Overflow
max semispace size(bytes): 249,036,800
max stack size(bytes): 18,153,472
GC time(ms): 694,560 (28.1%)
maxPause(ms): 4,420
number of GCs: 285
bytes allocated: 35,723,028,264
bytes copied: 35,462,871,828
max bytes live: 200,254,892
Command exited with non-zero status 1
1323.91user 1150.10system 43:05.29elapsed 95%CPU (0avgtext+0avgdata 0maxresident)k
0inputs+0outputs (3699major+597598minor)pagefaults 0swaps
make[1]: *** [mlton-compile] Error 1
make[1]: Leaving directory `/home/sweeks/mlton/src/mlton'
make: *** [/home/sweeks/mlton/src/mlton/mlton-compile] Error 2