2012-05-01-16-07-04-perf_bad-rebuild-alt-compiler

gistfile1.txt

myke rebuild-alt-compiler C:\Projects\Perf_Bad 
psi: filename = cmd.exe, arguments = /C ant build -buildfile build.xml, home = C:\Projects\Perf_Bad
Buildfile: C:\Projects\Perf_Bad\build.xml

init.jars.check:

init.jars:

init:
     [echo]        build time: 1 May 2012, 16:07:05
     [echo]      java version: Java HotSpot(TM) 64-Bit Server VM 1.6.0_25
     [echo]         java args: -Xss128m -Xms3072m -Xmx3072m -XX:+UseParallelGC -XX:MaxPermSize=1024M 
     [echo]        javac args: 
     [echo]       scalac args: -Xmacros -Ystatistics
     [echo]      build number: 3a0daf4b2e

locker.start:

locker.pre-lib:

locker.lib:

locker.pre-comp:

locker.comp:

locker.done:

quick.start:

quick.pre-lib:

quick.lib:

quick.newlibs:

quick.libs:

quick.newforkjoin:

quick.forkjoin:

quick.pre-comp:

quick.comp:
    [mkdir] Created dir: C:\Projects\Perf_Bad\build\quick\classes\compiler
[scalacfork] Compiling 496 files to C:\Projects\Perf_Bad\build\quick\classes\compiler
[scalacfork] Enabling statistics, measuring overhead = 51.999ns to 365.0981ns per timer
[scalacfork] *** Cumulative statistics at phase parser
[scalacfork] #created tree nodes  : 594415
[scalacfork] #created tree nodes by type: ArrayBuffer()
[scalacfork] *** Cumulative statistics at phase typer
[scalacfork] #created tree nodes  : 1478205
[scalacfork] #created tree nodes by type: ArrayBuffer()
[scalacfork] #retained nodes          : 738979
[scalacfork] #retained nodes by type  : ArrayBuffer(Select: 186221, Ident: 94219, TypeTree: 93951, Apply: 82621, This: 66385, ValDef: 35920, Literal: 33484, : 31851, DefDef: 26078, TypeApply: 14760, Block: 13357, CaseDef: 9792, Bind: 7383, If: 7380, Function: 4737, New: 4144, Match: 3309, ApplyImplicitView: 3164, Super: 2641, Template: 2613, ApplyToImplicitArgs: 2462, Assign: 2232, Typed: 1776, Import: 1772, ClassDef: 1709, PackageDef: 984, ModuleDef: 904, TypeDef: 808, Throw: 672, Try: 352, LabelDef: 350, UnApply: 348, Return: 323, Alternative: 252, BackQuotedIdent: 15, AppliedTypeTree: 7, Star: 3)
[scalacfork] #typechecked identifiers : 227636
[scalacfork] #typechecked selections  : 137621
[scalacfork] #typechecked applications: 93654
[scalacfork] #raw type creations      : 0
[scalacfork]   of which in failed     : 0
[scalacfork]   of which in implicits  : 0
[scalacfork] #unique types            : 748131
[scalacfork] #symbols                 : 734556
[scalacfork]   of which type symbols  : 0
[scalacfork]   of which class symbols : 0
[scalacfork] #base type seqs          : 0
[scalacfork] avg base type seq length : NaN
[scalacfork] #singleton base type seqs: 0
[scalacfork] #compound base type seqs : 0
[scalacfork] #typeref base type seqs  : 0
[scalacfork] #findMember ops          : 0
[scalacfork]   of which in failed     : 0
[scalacfork]   of which in implicits  : 0
[scalacfork] #notfound member         : 0
[scalacfork] #multiple member         : 0
[scalacfork] #asSeenFrom ops          : 0
[scalacfork] #subtype                 : 0
[scalacfork]   of which in failed     : 0
[scalacfork]   of which in implicits  : 0
[scalacfork]   of which in app impl   : 0
[scalacfork]   of which in improv     : 0
[scalacfork] #sametype                : 0
[scalacfork] ms type-flow-analysis: 0
[scalacfork] time spent typechecking  : 1 spans, 46064661700ns (100,0%)
[scalacfork] time classfilereading    : 882 spans, 1261266522ns (2,7%)
[scalacfork] time spent in implicits  : 20697 spans, 13422446067ns (29,1%)
[scalacfork]     successful in scope  : 12101 spans, 5889484588ns (12,8%)
[scalacfork]         failed in scope  : 18944 spans, 3122612513ns (6,8%)
[scalacfork]      successful of type  : 9773 spans, 3779039071ns (8,2%)
[scalacfork]          failed of type  : 16015 spans, 492911649ns (1,1%)
[scalacfork]        assembling parts  : 2936 spans, 642185258ns (1,4%)
[scalacfork]               matchesPT  : 62937 spans, 2524922392ns (5,5%)
[scalacfork] implicit cache hits      : 14021 (82,7%)
[scalacfork] time spent in failed     : 148830 spans, 0ns (0,0%)
[scalacfork]        failed apply      : 78308 spans, 37405353ns (0,1%)
[scalacfork]        failed op=        : 47402 spans, 499548806ns (1,1%)
[scalacfork] time spent ref scanning  : 9 spans, 979929ns (0,0%)
[scalacfork] micros by tree node      : ArrayBuffer(Apply: 15281646, Select: 13138023, Ident: 7796318, DefDef: 1522847, ClassDef: 1332287, If: 1020970, Match: 882089, AppliedTypeTree: 839175, Block: 691189, Literal: 626269, ModuleDef: 407386, ValDef: 405000, Function: 283773, TypeApply: 254229, New: 129102, ApplyToImplicitArgs: 87605, This: 85613, ApplyImplicitView: 77373, PackageDef: 75529, Typed: 69031, Bind: 64977, TypeTree: 42481, Assign: 29634, Super: 27882, Annotated: 21407, Try: 18627, ExistentialTypeTree: 18492, TypeDef: 13493, TypeBoundsTree: 12639, SelectFromTypeTree: 9181, Throw: 6039, LabelDef: 5377, SingletonTypeTree: 4450, CompoundTypeTree: 3829, Return: 3448, AssignOrNamedArg: 3253, Alternative: 2764, BackQuotedIdent: 2409, : 730, Star: 119)
[scalacfork] #visits by tree node     : ArrayBuffer(Ident: 245085, Select: 173807, Apply: 91945, TypeTree: 52403, Literal: 41399, DefDef: 26088, Block: 19161, This: 15619, ValDef: 15047, AppliedTypeTree: 14357, If: 7489, Bind: 7408, Function: 6715, TypeApply: 5795, ApplyImplicitView: 5507, New: 4875, Match: 3675, Assign: 3554, ApplyToImplicitArgs: 2710, Super: 2645, ClassDef: 1709, TypeBoundsTree: 1591, Typed: 1231, PackageDef: 984, ModuleDef: 904, : 841, SingletonTypeTree: 742, Throw: 678, Annotated: 627, TypeDef: 393, Try: 357, LabelDef: 353, Return: 328, Alternative: 252, ExistentialTypeTree: 186, SelectFromTypeTree: 82, AssignOrNamedArg: 80, CompoundTypeTree: 50, BackQuotedIdent: 32, Star: 3)
[scalacfork] avg micros by tree node  : ArrayBuffer(ClassDef: 779, ModuleDef: 450, Match: 240, Apply: 166, If: 136, SelectFromTypeTree: 111, ExistentialTypeTree: 99, CompoundTypeTree: 76, PackageDef: 76, Select: 75, BackQuotedIdent: 75, AppliedTypeTree: 58, DefDef: 58, Typed: 56, Try: 52, TypeApply: 43, Function: 42, AssignOrNamedArg: 40, Star: 39, Block: 36, Annotated: 34, TypeDef: 34, ApplyToImplicitArgs: 32, Ident: 31, New: 26, ValDef: 26, LabelDef: 15, Literal: 15, ApplyImplicitView: 14, Return: 10, Super: 10, Alternative: 10, Throw: 8, Assign: 8, Bind: 8, TypeBoundsTree: 7, SingletonTypeTree: 5, This: 5, TypeTree: 0, : 0)
[scalacfork] time spent in <:<        : 0 spans, 0ns (0,0%)
[scalacfork] time spent in findmember : 0 spans, 0ns (0,0%)
[scalacfork] time spent in asSeenFrom : 0 spans, 0ns (0,0%)
[scalacfork] #implicit searches       : 20697
[scalacfork] #tried, plausible, matching, typed, found implicits: 0, 5950, 5950, 5859, 5839
[scalacfork] #implicit improves tests : 15346
[scalacfork] #implicit improves cached: 12664
[scalacfork] #implicit inscope hits   : 3505
[scalacfork] #implicit oftype hits    : 2354
[scalacfork] #ctr1                    : 0
[scalacfork] #ctr2                    : 0
[scalacfork] #ctr3                    : 0
[scalacfork] #ctr4                    : 0
[scalacfork] #counter1                : 0
[scalacfork] #counter2                : 0
[scalacfork] #timer1                  : 0 spans, 0ns
[scalacfork] #timer2                  : 0 spans, 0ns
[scalacfork] *** Cumulative statistics at phase erasure
[scalacfork] #created tree nodes  : 3576684
[scalacfork] #created tree nodes by type: ArrayBuffer()
[scalacfork] #retained nodes          : 1373435
[scalacfork] #retained nodes by type  : ArrayBuffer(Select: 290283, Apply: 242078, TypeTree: 217803, Ident: 117852, This: 103124, ValDef: 74518, DefDef: 63197, : 56238, Block: 45278, Literal: 41446, TypeApply: 32569, New: 17128, If: 15738, ClassDef: 9483, Template: 9483, Super: 9196, Typed: 7306, Assign: 3181, ApplyImplicitView: 3160, LabelDef: 2886, CaseDef: 2794, ApplyToImplicitArgs: 2454, ArrayValue: 2030, Throw: 2004, PackageDef: 984, Try: 389, Match: 296, Return: 283, Bind: 231, ApplyDynamic: 23)
[scalacfork] #typechecked identifiers : 352667
[scalacfork] #typechecked selections  : 445999
[scalacfork] #typechecked applications: 396881
[scalacfork] #raw type creations      : 0
[scalacfork]   of which in failed     : 0
[scalacfork]   of which in implicits  : 0
[scalacfork] #unique types            : 894470
[scalacfork] #symbols                 : 1351905
[scalacfork]   of which type symbols  : 0
[scalacfork]   of which class symbols : 0
[scalacfork] #base type seqs          : 0
[scalacfork] avg base type seq length : NaN
[scalacfork] #singleton base type seqs: 0
[scalacfork] #compound base type seqs : 0
[scalacfork] #typeref base type seqs  : 0
[scalacfork] #findMember ops          : 0
[scalacfork]   of which in failed     : 0
[scalacfork]   of which in implicits  : 0
[scalacfork] #notfound member         : 0
[scalacfork] #multiple member         : 0
[scalacfork] #asSeenFrom ops          : 0
[scalacfork] #subtype                 : 0
[scalacfork]   of which in failed     : 0
[scalacfork]   of which in implicits  : 0
[scalacfork]   of which in app impl   : 0
[scalacfork]   of which in improv     : 0
[scalacfork] #sametype                : 0
[scalacfork] ms type-flow-analysis: 0
[scalacfork] #ctr1                    : 0
[scalacfork] #ctr2                    : 0
[scalacfork] #ctr3                    : 0
[scalacfork] #ctr4                    : 0
[scalacfork] #counter1                : 0
[scalacfork] #counter2                : 0
[scalacfork] #timer1                  : 0 spans, 0ns
[scalacfork] #timer2                  : 0 spans, 0ns
[scalacfork] *** Cumulative statistics at phase cleanup
[scalacfork] #created tree nodes  : 4228280
[scalacfork] #created tree nodes by type: ArrayBuffer()
[scalacfork] #retained nodes          : 1681450
[scalacfork] #retained nodes by type  : ArrayBuffer(Select: 375329, Apply: 265401, TypeTree: 246229, Ident: 158785, This: 130186, ValDef: 95644, : 84444, DefDef: 67860, Literal: 54060, Block: 50223, TypeApply: 31258, If: 23314, New: 22170, Assign: 18619, Super: 9610, ClassDef: 9483, Template: 9483, Typed: 7329, Throw: 6391, ApplyImplicitView: 3149, LabelDef: 2886, CaseDef: 2817, ApplyToImplicitArgs: 2419, ArrayValue: 2086, PackageDef: 984, Try: 412, Return: 329, Match: 296, Bind: 254)
[scalacfork] #typechecked identifiers : 376007
[scalacfork] #typechecked selections  : 495878
[scalacfork] #typechecked applications: 424543
[scalacfork] #raw type creations      : 0
[scalacfork]   of which in failed     : 0
[scalacfork]   of which in implicits  : 0
[scalacfork] #unique types            : 921272
[scalacfork] #symbols                 : 1457255
[scalacfork]   of which type symbols  : 0
[scalacfork]   of which class symbols : 0
[scalacfork] #base type seqs          : 0
[scalacfork] avg base type seq length : NaN
[scalacfork] #singleton base type seqs: 0
[scalacfork] #compound base type seqs : 0
[scalacfork] #typeref base type seqs  : 0
[scalacfork] #findMember ops          : 0
[scalacfork]   of which in failed     : 0
[scalacfork]   of which in implicits  : 0
[scalacfork] #notfound member         : 0
[scalacfork] #multiple member         : 0
[scalacfork] #asSeenFrom ops          : 0
[scalacfork] #subtype                 : 0
[scalacfork]   of which in failed     : 0
[scalacfork]   of which in implicits  : 0
[scalacfork]   of which in app impl   : 0
[scalacfork]   of which in improv     : 0
[scalacfork] #sametype                : 0
[scalacfork] ms type-flow-analysis: 0
[scalacfork] #ctr1                    : 0
[scalacfork] #ctr2                    : 0
[scalacfork] #ctr3                    : 0
[scalacfork] #ctr4                    : 0
[scalacfork] #counter1                : 0
[scalacfork] #counter2                : 0
[scalacfork] #timer1                  : 0 spans, 0ns
[scalacfork] #timer2                  : 0 spans, 0ns
[scalacfork] warning: there were 82 deprecation warnings; re-run with -deprecation for details
[scalacfork] warning: there were 23 unchecked warnings; re-run with -unchecked for details
[scalacfork] two warnings found
[propertyfile] Creating new property file: C:\Projects\Perf_Bad\build\quick\classes\compiler\compiler.properties
     [copy] Copying 57 files to C:\Projects\Perf_Bad\build\quick\classes\compiler
[stopwatch] [quick.comp.timer: 2:10.009 sec]