Skip to content

Instantly share code, notes, and snippets.

@TruffleSqueak-Bot

TruffleSqueak-Bot/b5e925d-1-summary.md

Created July 26, 2021 08:28
Show Gist options
  • Save TruffleSqueak-Bot/7352e06eaee6ae5069bd1044b0228686 to your computer and use it in GitHub Desktop.
Save TruffleSqueak-Bot/7352e06eaee6ae5069bd1044b0228686 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
b5e925d-1-summary.md

Steady (after 50 iterations)

Benchmark Name Min Geomean Median Mean Max Total (ms) Total (min)
Bounce 363 407 368.53 364 368.41 73705 1.23
DeltaBlue 138 236 151.09 143 150.26 30218 0.5
Havlak 1000 1098 1053.28 1062 1053.07 210656 3.51
Json 222 267 226.13 223.5 226.04 45226 0.75
List 449 487 454.66 454 454.63 90932 1.52
Mandelbrot 140 158 140.64 140 140.62 28128 0.47
NBody 194 227 197.61 195 197.53 39522 0.66
Permute 210 244 213.08 211 212.99 42615 0.71
Queens 171 226 177.78 173 177.46 35556 0.59
Richards 876 919 882.71 878 882.66 176541 2.94
Sieve 166 212 169.13 167 168.97 33826 0.56
Storage 180 213 185.29 182 185.15 37058 0.62
Towers 273 311 275.94 274 275.87 55188 0.92
4382 5005 4495.85 4466.5 4493.67 899171 14.99
Benchmarks ran on graalvm-ee-java11-21.2.0. 
Runtime:   graalvm-ee-java11-21.2.0
System:    Host: swaarena Kernel: 4.9.0-14-amd64 x86_64 (64 bit) Console: tty 2
         Distro: Debian GNU/Linux 9 (stretch)
Machine:   Device: server System: Dell product: PowerEdge 2950
         Mobo: Dell model: 0H603H v: A05 BIOS: Dell v: 2.5.0 date: 09/12/2008
CPU(s):    2 Quad core Intel Xeon E5410s (-HT-MCP-SMP-) cache: 12288 KB 
         clock speeds: max: 2333 MHz 1: 2333 MHz 2: 2333 MHz 3: 2333 MHz 4: 2333 MHz 5: 2333 MHz 6: 2333 MHz
         7: 2333 MHz 8: 2333 MHz
Memory:    Used/Total: 1435.3/32174.3MB
         Array-1 capacity: 63.75 GB devices: 8 EC: Multi-bit ECC
         Device-1: DIMM1 size: 4 GB speed: 667 MHz type: DDR2 FB-DIMM
         Device-2: DIMM2 size: 4 GB speed: 667 MHz type: DDR2 FB-DIMM
         Device-3: DIMM3 size: 4 GB speed: 667 MHz type: DDR2 FB-DIMM
         Device-4: DIMM4 size: 4 GB speed: 667 MHz type: DDR2 FB-DIMM
         Device-5: DIMM5 size: 4 GB speed: 667 MHz type: DDR2 FB-DIMM
         Device-6: DIMM6 size: 4 GB speed: 667 MHz type: DDR2 FB-DIMM
         Device-7: DIMM7 size: 4 GB speed: 667 MHz type: DDR2 FB-DIMM
         Device-8: DIMM8 size: 4 GB speed: 667 MHz type: DDR2 FB-DIMM
Display the source blob
Display the rendered blob
Raw
b5e925d-2-steady.svg
Loading
Sorry, something went wrong. Reload?
Sorry, we cannot display this file.
Sorry, this file is invalid so it cannot be displayed.
Display the source blob
Display the rendered blob
Raw
b5e925d-3-warmup.svg
Loading
Sorry, something went wrong. Reload?
Sorry, we cannot display this file.
Sorry, this file is invalid so it cannot be displayed.
Raw
b5e925dc-Bounce.trace.log
# Bounce (iterations: 250, problem size: 1500) on Mon Jul 26 08:07:27 UTC 2021
# `/home/fniephaus/bin/graalvm-ee-java11-21.2.0/bin/trufflesqueak --experimental-options --smalltalk.disable-startup --smalltalk.disable-interrupts --engine.Mode=default --engine.MultiTier=false --engine.DynamicCompilationThresholds=false --engine.TraceCompilation --engine.CompilationStatistics --log.file="/home/fniephaus/dev/are-we-fast-yet/traces/Bounce.trace.log" --quiet --code "FileStream startUp: true. Harness new run: #(nil 'Bounce' 250 1500)" /home/fniephaus/dev/are-we-fast-yet/benchmarks/Smalltalk/AWFY64-sista-fbc.image`
[engine] opt done Ball>>bounce |AST 207|Tier 2|Time 336( 294+42 )ms|Inlined 8Y 0N|IR 635/ 699|CodeSize 2055|Addr 0x7efee2755390|Src n/a
[engine] opt done Bounce>>benchmark |AST 36|Tier 2|Time 364( 306+58 )ms|Inlined 9Y 0N|IR 770/ 980|CodeSize 3130|Addr 0x7efee275aa90|Src n/a
[engine] opt done Number>>negated |AST 14|Tier 2|Time 32( 29+3 )ms|Inlined 0Y 0N|IR 24/ 93|CodeSize 386|Addr 0x7efee2764c10|Src n/a
[engine] opt done Number>>abs |AST 29|Tier 2|Time 62( 57+4 )ms|Inlined 1Y 0N|IR 61/ 165|CodeSize 555|Addr 0x7efee2766d10|Src n/a
[engine] opt done SomRandom>>next |AST 39|Tier 2|Time 67( 62+5 )ms|Inlined 0Y 0N|IR 83/ 160|CodeSize 510|Addr 0x7efee2773b90|Src n/a
[engine] opt done SequenceableCollection>>do: <split-6ad11a56> |AST 73|Tier 2|Time 554( 341+213 )ms|Inlined 10Y 0N|IR 938/ 3668|CodeSize 14327|Addr 0x7efee277a590|Src n/a
[engine] opt done Behavior>>new <split-63429932> |AST 26|Tier 2|Time 58( 53+5 )ms|Inlined 0Y 0N|IR 34/ 269|CodeSize 973|Addr 0x7efee2789f10|Src n/a
[engine] opt done Ball>>initialize: |AST 122|Tier 2|Time 211( 199+12 )ms|Inlined 4Y 0N|IR 449/ 460|CodeSize 1045|Addr 0x7efee2790a90|Src n/a
[engine] opt done Ball class>>new: |AST 31|Tier 2|Time 187( 171+16 )ms|Inlined 6Y 0N|IR 391/ 492|CodeSize 1362|Addr 0x7efee27a1a90|Src n/a
[engine] opt done SomRandom>>initialize |AST 10|Tier 2|Time 29( 26+3 )ms|Inlined 0Y 0N|IR 68/ 79|CodeSize 238|Addr 0x7efee27a3f90|Src n/a
[engine] opt done Behavior>>new <split-4066f278> |AST 28|Tier 2|Time 65( 62+3 )ms|Inlined 1Y 0N|IR 34/ 100|CodeSize 422|Addr 0x7efee27a5c10|Src n/a
[engine] opt done SomRandom class>>new |AST 26|Tier 2|Time 77( 73+4 )ms|Inlined 3Y 0N|IR 34/ 100|CodeSize 422|Addr 0x7efee27a6290|Src n/a
[engine] opt done Bounce>>verifyResult: |AST 15|Tier 2|Time 29( 27+2 )ms|Inlined 0Y 0N|IR 25/ 28|CodeSize 192|Addr 0x7efee27a8710|Src n/a
[engine] opt done Bounce>>benchmark |AST 169|Tier 2|Time 918( 566+353 )ms|Inlined 22Y 0N|IR 1942/ 9193|CodeSize 35809|Addr 0x7efee27a8f90|Src n/a
[engine] opt done Benchmark>>innerBenchmarkLoop: |AST 59|Tier 2|Time 1012( 492+520 )ms|Inlined 24Y 0N|IR 2034/ 9470|CodeSize 36285|Addr 0x7efee27bc390|Src n/a
[engine] opt done String class>>new: |AST 51|Tier 2|Time 216( 198+17 )ms|Inlined 2Y 1N|IR 213/ 474|CodeSize 1885|Addr 0x7efee27dcb10|Src n/a
[engine] opt done SmallInteger>>printString |AST 152|Tier 2|Time 266( 245+21 )ms|Inlined 4Y 1N|IR 362/ 377|CodeSize 1185|Addr 0x7efee27df990|Src n/a
[engine] Truffle runtime statistics for engine 3
Compilations : 18
Success : 17
Temporary Bailouts : 0
Permanent Bailouts : 0
Failed : 0
Interrupted : 1
Invalidated : 0
Queues : 18
Dequeues : 0
Splits : 228
Compilation Accuracy : 1.000000
Queue Accuracy : 1.000000
Compilation Utilization : 0.047953
Remaining Compilation Queue : 0
Time to queue : count= 18, sum= 203169, min= 45, average= 11287.21, max= 92744 (milliseconds), maxTarget=SmallInteger>>printString
Time waiting in queue : count= 18, sum= 1143, min= 0, average= 63.51, max= 278 (milliseconds), maxTarget=Number>>abs
Time for compilation : count= 17, sum= 4523, min= 30, average= 266.06, max= 1013 (milliseconds), maxTarget=Benchmark>>innerBenchmarkLoop:
Truffle Tier : count= 17, sum= 3200, min= 26, average= 188.24, max= 565 (milliseconds), maxTarget=Bounce>>benchmark
Graal Tier : count= 17, sum= 904, min= 1, average= 53.20, max= 390 (milliseconds), maxTarget=Benchmark>>innerBenchmarkLoop:
Code Installation : count= 17, sum= 418, min= 1, average= 24.63, max= 131 (milliseconds), maxTarget=Benchmark>>innerBenchmarkLoop:
Truffle node count : count= 17, sum= 7969, min= 18, average= 468.76, max= 1844, maxTarget=Benchmark>>innerBenchmarkLoop:
Trivial : count= 17, sum= 3094, min= 8, average= 182.00, max= 704, maxTarget=Benchmark>>innerBenchmarkLoop:
Non Trivial : count= 17, sum= 4875, min= 10, average= 286.76, max= 1140, maxTarget=Benchmark>>innerBenchmarkLoop:
Monomorphic : count= 17, sum= 4853, min= 10, average= 285.47, max= 1138, maxTarget=Benchmark>>innerBenchmarkLoop:
Polymorphic : count= 17, sum= 22, min= 0, average= 1.29, max= 9, maxTarget=String class>>new:
Megamorphic : count= 17, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Ball>>bounce
Truffle call count : count= 17, sum= 96, min= 0, average= 5.65, max= 24, maxTarget=Benchmark>>innerBenchmarkLoop:
Indirect : count= 17, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Ball>>bounce
Direct : count= 17, sum= 96, min= 0, average= 5.65, max= 24, maxTarget=Benchmark>>innerBenchmarkLoop:
Dispatched : count= 17, sum= 2, min= 0, average= 0.12, max= 1, maxTarget=String class>>new:
Inlined : count= 17, sum= 94, min= 0, average= 5.53, max= 24, maxTarget=Benchmark>>innerBenchmarkLoop:
---------- :
Cloned : count= 17, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Ball>>bounce
Not Cloned : count= 17, sum= 96, min= 0, average= 5.65, max= 24, maxTarget=Benchmark>>innerBenchmarkLoop:
Truffle loops : count= 17, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Ball>>bounce
Graal node count :
After Truffle Tier : count= 17, sum= 8097, min= 24, average= 476.29, max= 2034, maxTarget=Benchmark>>innerBenchmarkLoop:
After Graal Tier : count= 17, sum= 26807, min= 28, average= 1576.88, max= 9470, maxTarget=Benchmark>>innerBenchmarkLoop:
Graal compilation result :
Code size : count= 17, sum= 100781, min= 192, average= 5928.29, max= 36285, maxTarget=Benchmark>>innerBenchmarkLoop:
Total frame size : count= 17, sum= 2080, min= 32, average= 122.35, max= 624, maxTarget=Benchmark>>innerBenchmarkLoop:
Exception handlers : count= 17, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Ball>>bounce
Infopoints : count= 17, sum= 940, min= 4, average= 55.29, max= 304, maxTarget=Benchmark>>innerBenchmarkLoop:
CALL : count= 17, sum= 761, min= 4, average= 44.76, max= 243, maxTarget=Benchmark>>innerBenchmarkLoop:
IMPLICIT_EXCEPTION : count= 17, sum= 176, min= 0, average= 10.35, max= 59, maxTarget=Benchmark>>innerBenchmarkLoop:
SAFEPOINT : count= 17, sum= 3, min= 0, average= 0.18, max= 2, maxTarget=Benchmark>>innerBenchmarkLoop:
Marks : count= 17, sum= 109, min= 6, average= 6.41, max= 9, maxTarget=Ball>>bounce
Data references : count= 17, sum= 713, min= 2, average= 41.94, max= 241, maxTarget=Benchmark>>innerBenchmarkLoop:
Raw
b5e925dc-DeltaBlue.trace.log
# DeltaBlue (iterations: 250, problem size: 12000) on Mon Jul 26 08:14:19 UTC 2021
# `/home/fniephaus/bin/graalvm-ee-java11-21.2.0/bin/trufflesqueak --experimental-options --smalltalk.disable-startup --smalltalk.disable-interrupts --engine.Mode=default --engine.MultiTier=false --engine.DynamicCompilationThresholds=false --engine.TraceCompilation --engine.CompilationStatistics --log.file="/home/fniephaus/dev/are-we-fast-yet/traces/DeltaBlue.trace.log" --quiet --code "FileStream startUp: true. Harness new run: #(nil 'DeltaBlue' 250 12000)" /home/fniephaus/dev/are-we-fast-yet/benchmarks/Smalltalk/AWFY64-sista-fbc.image`
[engine] opt done Behavior>>new <split-187eb9a8> |AST 26|Tier 2|Time 143( 127+16 )ms|Inlined 0Y 0N|IR 33/ 99|CodeSize 416|Addr 0x7ff8ab7cf690|Src n/a
[engine] opt done Vector>>initialize: |AST 30|Tier 2|Time 151( 140+11 )ms|Inlined 0Y 0N|IR 139/ 339|CodeSize 942|Addr 0x7ff8ab7d1910|Src n/a
[engine] opt done Vector class>>new: |AST 29|Tier 2|Time 174( 166+9 )ms|Inlined 2Y 0N|IR 57/ 366|CodeSize 1218|Addr 0x7ff8ab7d2a90|Src n/a
[engine] opt done Strength class>>absoluteWeakest |AST 10|Tier 2|Time 35( 33+2 )ms|Inlined 0Y 0N|IR 21/ 29|CodeSize 207|Addr 0x7ff8ab7d8890|Src n/a
[engine] opt done Variable>>initialize |AST 56|Tier 2|Time 150( 137+13 )ms|Inlined 4Y 0N|IR 208/ 625|CodeSize 1755|Addr 0x7ff8ab7ed890|Src n/a
[engine] opt inv. Variable>>initialize |AST 56|Calls/Thres 21530/ 3|CallsAndLoop/Thres 21530/ 1000|Src n/a|Reason Latest layout assumption Layout no longer valid
[engine] opt done Vector>>isEmpty |AST 18|Tier 2|Time 48( 45+3 )ms|Inlined 0Y 0N|IR 31/ 48|CodeSize 257|Addr 0x7ff8ab80a090|Src n/a
[engine] opt done BinaryConstraint>>output |AST 20|Tier 2|Time 52( 47+4 )ms|Inlined 0Y 0N|IR 37/ 66|CodeSize 379|Addr 0x7ff8ab80a510|Src n/a
[engine] opt done Vector>>append: |AST 60|Tier 2|Time 128( 112+16 )ms|Inlined 0Y 0N|IR 223/ 703|CodeSize 2090|Addr 0x7ff8ab815d10|Src n/a
[engine] opt done Variable>>mark: |AST 11|Tier 2|Time 70( 67+3 )ms|Inlined 0Y 0N|IR 73/ 90|CodeSize 256|Addr 0x7ff8ab81ea10|Src n/a
[engine] opt done Variable>>addConstraint: |AST 24|Tier 2|Time 161( 138+23 )ms|Inlined 1Y 0N|IR 289/ 768|CodeSize 2272|Addr 0x7ff8ab825d10|Src n/a
[engine] opt done Strength>>weaker: |AST 27|Tier 2|Time 62( 59+3 )ms|Inlined 0Y 0N|IR 39/ 64|CodeSize 294|Addr 0x7ff8ab82d210|Src n/a
[engine] opt done SomDictionary>>bucketIdx: |AST 40|Tier 2|Time 73( 67+7 )ms|Inlined 0Y 0N|IR 91/ 213|CodeSize 753|Addr 0x7ff8ab839990|Src n/a
[engine] opt done Vector>>forEach: <split-6ad5923a> |AST 79|Tier 2|Time 145( 114+31 )ms|Inlined 1Y 0N|IR 201/ 1281|CodeSize 4440|Addr 0x7ff8ab83d510|Src n/a
[engine] opt done Integer>>>> |AST 31|Tier 2|Time 47( 43+3 )ms|Inlined 0Y 0N|IR 39/ 113|CodeSize 445|Addr 0x7ff8ab846c10|Src n/a
[engine] opt done SomDictionary>>hash: |AST 60|Tier 2|Time 84( 80+4 )ms|Inlined 1Y 0N|IR 33/ 108|CodeSize 419|Addr 0x7ff8ab847790|Src n/a
[engine] opt done SomDictionary>>bucket: |AST 33|Tier 2|Time 84( 79+5 )ms|Inlined 1Y 0N|IR 155/ 117|CodeSize 587|Addr 0x7ff8ab853610|Src n/a
[engine] opt done DictIdEntry>>match:key: |AST 29|Tier 2|Time 107( 66+41 )ms|Inlined 0Y 0N|IR 43/ 73|CodeSize 375|Addr 0x7ff8ab859b90|Src n/a
[engine] opt done SomDictionary>>at: |AST 86|Tier 2|Time 237( 230+7 )ms|Inlined 5Y 0N|IR 238/ 191|CodeSize 853|Addr 0x7ff8ab85c210|Src n/a
[engine] opt done Planner>>addPropagate:mark: |AST 131|Tier 2|Time 616( 510+106 )ms|Inlined 22Y 0N|IR 873/ 1007|CodeSize 3167|Addr 0x7ff8ab860810|Src n/a
[engine] opt done Strength class>>SymRequired |AST 10|Tier 2|Time 32( 30+2 )ms|Inlined 0Y 0N|IR 21/ 29|CodeSize 207|Addr 0x7ff8ab86af90|Src n/a
[engine] opt done Behavior>>new <split-15723761> |AST 26|Tier 2|Time 56( 51+5 )ms|Inlined 0Y 0N|IR 92/ 270|CodeSize 971|Addr 0x7ff8ab86b410|Src n/a
[engine] opt done Strength>>stronger: |AST 27|Tier 2|Time 47( 45+3 )ms|Inlined 0Y 0N|IR 39/ 64|CodeSize 294|Addr 0x7ff8ab872910|Src n/a
[engine] opt done Strength class>>of: |AST 23|Tier 2|Time 165( 158+7 )ms|Inlined 6Y 0N|IR 285/ 206|CodeSize 917|Addr 0x7ff8ab872e10|Src n/a
[engine] opt deopt Vector>>forEach: <split-6ad5923a> |AST 79|Src n/a
[engine] opt inv. Planner>>addPropagate:mark: |AST 143|Calls/Thres 7807/ 3|CallsAndLoop/Thres 15615/ 1000|Src n/a|Reason validRootAssumption Split call node
[engine] opt inv. Vector>>forEach: <split-6ad5923a> |AST 79|Calls/Thres 3514/ 3|CallsAndLoop/Thres 7029/ 1000|Src n/a|Reason validRootAssumption Split call node
[engine] opt done BinaryConstraint>>initializeVar:var:strength:addTo: |AST 34|Tier 2|Time 180( 177+3 )ms|Inlined 8Y 0N|IR 353/ 5|CodeSize 120|Addr 0x7ff8ab87bc90|Src n/a
[engine] opt done Vector>>forEach: <split-6ad5923a> |AST 79|Tier 2|Time 146( 102+44 )ms|Inlined 2Y 0N|IR 246/ 2172|CodeSize 8113|Addr 0x7ff8ab880610|Src n/a
[engine] opt done BinaryConstraint>>output |AST 20|Tier 2|Time 39( 35+4 )ms|Inlined 0Y 0N|IR 64/ 84|CodeSize 492|Addr 0x7ff8ab884d10|Src n/a
[engine] opt done Variable>>value: |AST 11|Tier 2|Time 123( 120+3 )ms|Inlined 0Y 0N|IR 74/ 90|CodeSize 256|Addr 0x7ff8ab885390|Src n/a
[engine] opt done Variable>>determinedBy: |AST 11|Tier 2|Time 27( 24+3 )ms|Inlined 0Y 0N|IR 56/ 75|CodeSize 299|Addr 0x7ff8ab885a10|Src n/a
[engine] opt done BinaryConstraint>>chooseMethod: |AST 241|Tier 2|Time 145( 125+21 )ms|Inlined 3Y 0N|IR 349/ 676|CodeSize 2429|Addr 0x7ff8ab88b610|Src n/a
[engine] opt done BinaryConstraint>>inputsDo: |AST 60|Tier 2|Time 125( 92+33 )ms|Inlined 5Y 0N|IR 410/ 2142|CodeSize 7326|Addr 0x7ff8ab891f90|Src n/a
[engine] opt done EqualityConstraint>>initializeVar:var:strength:addTo: |AST 45|Tier 2|Time 825( 822+4 )ms|Inlined 35Y 1N|IR 385/ 5|CodeSize 120|Addr 0x7ff8ab895e90|Src n/a
[engine] opt done AbstractConstraint>>satisfy:propagate: |AST 21|Tier 2|Time 53( 49+4 )ms|Inlined 1Y 0N|IR 115/ 125|CodeSize 259|Addr 0x7ff8ab897f10|Src n/a
[engine] opt done BinaryConstraint>>chooseMethod: |AST 241|Tier 2|Time 160( 138+23 )ms|Inlined 4Y 0N|IR 425/ 837|CodeSize 2981|Addr 0x7ff8ab899c10|Src n/a
[engine] opt done Variable>>walkStrength: |AST 11|Tier 2|Time 30( 26+3 )ms|Inlined 0Y 0N|IR 60/ 167|CodeSize 431|Addr 0x7ff8ab89be10|Src n/a
[engine] opt deopt Vector>>forEach: <split-6ad5923a> |AST 79|Src n/a
[engine] opt inv. Vector>>forEach: <split-6ad5923a> |AST 79|Calls/Thres 10279/ 3|CallsAndLoop/Thres 27325/ 1000|Src n/a|Reason validRootAssumption Split call node
[engine] opt inv. Variable>>addConstraint: |AST 24|Calls/Thres 3862/ 3|CallsAndLoop/Thres 3862/ 1000|Src n/a|Reason frame version
[engine] opt inv. Vector>>append: |AST 124|Calls/Thres 3572/ 3|CallsAndLoop/Thres 3572/ 1000|Src n/a|Reason frame version
[engine] opt done EqualityConstraint class>>var:var:strength:addTo: |AST 40|Tier 2|Time 1089(1083+6 )ms|Inlined 52Y 0N|IR 503/ 5|CodeSize 120|Addr 0x7ff8ab89c590|Src n/a
[engine] opt done Vector>>removeFirst |AST 53|Tier 2|Time 99( 94+6 )ms|Inlined 1Y 0N|IR 124/ 166|CodeSize 634|Addr 0x7ff8ab8a7490|Src n/a
[engine] opt done Vector>>append: |AST 124|Tier 2|Time 299( 237+63 )ms|Inlined 0Y 0N|IR 641/ 2314|CodeSize 9449|Addr 0x7ff8ab8afe90|Src n/a
[engine] opt done Variable>>stay: |AST 11|Tier 2|Time 30( 27+3 )ms|Inlined 0Y 0N|IR 76/ 93|CodeSize 256|Addr 0x7ff8ab8b4d90|Src n/a
[engine] opt done Vector>>forEach: <split-6ad5923a> |AST 79|Tier 2|Time 447( 298+149 )ms|Inlined 3Y 0N|IR 940/ 3922|CodeSize 15620|Addr 0x7ff8ab8b5890|Src n/a
[engine] opt done Symbol>>= <split-4917d36b> |AST 28|Tier 2|Time 40( 37+3 )ms|Inlined 0Y 0N|IR 58/ 48|CodeSize 278|Addr 0x7ff8ab8bda90|Src n/a
[engine] opt done BinaryConstraint>>recalculate |AST 147|Tier 2|Time 170( 152+18 )ms|Inlined 9Y 0N|IR 607/ 531|CodeSize 1562|Addr 0x7ff8ab8beb10|Src n/a
[engine] opt done Symbol>>= <split-3e134896> |AST 28|Tier 2|Time 43( 40+3 )ms|Inlined 0Y 0N|IR 58/ 75|CodeSize 275|Addr 0x7ff8ab8bfc90|Src n/a
[engine] opt done Planner>>addConstraintsConsuming:to: |AST 57|Tier 2|Time 388( 220+168 )ms|Inlined 4Y 0N|IR 986/ 2231|CodeSize 9873|Addr 0x7ff8ab8c2410|Src n/a
[engine] opt done EqualityConstraint>>execute |AST 80|Tier 2|Time 94( 86+8 )ms|Inlined 3Y 0N|IR 281/ 258|CodeSize 670|Addr 0x7ff8ab8cc010|Src n/a
[engine] opt done Strength>>weakest: |AST 25|Tier 2|Time 59( 57+3 )ms|Inlined 1Y 0N|IR 41/ 65|CodeSize 275|Addr 0x7ff8ab8ccd90|Src n/a
[engine] opt done Plan>>execute |AST 20|Tier 2|Time 107( 98+9 )ms|Inlined 4Y 0N|IR 364/ 246|CodeSize 807|Addr 0x7ff8ab8cd290|Src n/a
[engine] opt done BinaryConstraint>>markUnsatisfied |AST 10|Tier 2|Time 30( 27+3 )ms|Inlined 0Y 0N|IR 60/ 168|CodeSize 458|Addr 0x7ff8ab8ce310|Src n/a
[engine] opt done Vector>>forEach: <split-5cbb84b1> |AST 79|Tier 2|Time 210( 138+72 )ms|Inlined 5Y 0N|IR 550/ 3061|CodeSize 11929|Addr 0x7ff8ab8cea90|Src n/a
[engine] opt done Planner>>addConstraintsConsuming:to: |AST 45|Tier 2|Time 76( 71+5 )ms|Inlined 1Y 0N|IR 136/ 161|CodeSize 535|Addr 0x7ff8ab8d3790|Src n/a
[engine] opt inv. Variable>>determinedBy: |AST 11|Calls/Thres 22880/ 3|CallsAndLoop/Thres 22880/ 1000|Src n/a|Reason Profiled Argument Types
[engine] opt inv. Vector>>isEmpty |AST 18|Calls/Thres 1914/ 3|CallsAndLoop/Thres 1914/ 1000|Src n/a|Reason Profiled Argument Types
[engine] opt inv. Vector>>removeFirst |AST 53|Calls/Thres 33740/ 3|CallsAndLoop/Thres 33740/ 1000|Src n/a|Reason Profiled Argument Types
[engine] opt done Planner>>newMark |AST 19|Tier 2|Time 51( 46+5 )ms|Inlined 0Y 0N|IR 76/ 153|CodeSize 479|Addr 0x7ff8ab8d4690|Src n/a
[engine] opt done Vector>>removeFirst |AST 53|Tier 2|Time 156( 150+6 )ms|Inlined 1Y 0N|IR 123/ 166|CodeSize 634|Addr 0x7ff8ab8d9290|Src n/a
[engine] opt done Vector class>>with: |AST 43|Tier 2|Time 202( 192+10 )ms|Inlined 4Y 0N|IR 281/ 396|CodeSize 1336|Addr 0x7ff8ab8e0010|Src n/a
[engine] opt done Variable>>determinedBy: |AST 11|Tier 2|Time 28( 25+4 )ms|Inlined 0Y 0N|IR 56/ 91|CodeSize 359|Addr 0x7ff8ab8e3410|Src n/a
[engine] opt done Vector>>isEmpty |AST 18|Tier 2|Time 35( 32+2 )ms|Inlined 0Y 0N|IR 31/ 48|CodeSize 257|Addr 0x7ff8ab8e4910|Src n/a
[engine] opt done Vector class>>new: |AST 29|Tier 2|Time 99( 86+12 )ms|Inlined 2Y 0N|IR 137/ 462|CodeSize 1346|Addr 0x7ff8ab8e5090|Src n/a
[engine] opt done Vector>>initialize: |AST 30|Tier 2|Time 57( 50+7 )ms|Inlined 0Y 0N|IR 139/ 339|CodeSize 942|Addr 0x7ff8ab8e5f10|Src n/a
[engine] opt done Planner>>addPropagate:mark: <split-4905c46b> |AST 131|Tier 2|Time 919( 624+295 )ms|Inlined 25Y 0N|IR 2236/ 3033|CodeSize 13126|Addr 0x7ff8ab8e9810|Src n/a
[engine] opt done Planner>>addPropagate:mark: <split-66434cc8> |AST 131|Tier 2|Time 899( 610+290 )ms|Inlined 25Y 0N|IR 2236/ 3033|CodeSize 13126|Addr 0x7ff8ab8f7c90|Src n/a
[engine] opt done Variable>>initialize |AST 56|Tier 2|Time 116( 103+13 )ms|Inlined 4Y 0N|IR 310/ 712|CodeSize 1977|Addr 0x7ff8ab904d10|Src n/a
[engine] opt done BinaryConstraint>>output |AST 20|Tier 2|Time 43( 38+5 )ms|Inlined 0Y 0N|IR 95/ 143|CodeSize 528|Addr 0x7ff8ab908810|Src n/a
[engine] opt done BinaryConstraint>>chooseMethod: |AST 241|Tier 2|Time 250( 142+107 )ms|Inlined 4Y 0N|IR 575/ 1023|CodeSize 3908|Addr 0x7ff8ab909590|Src n/a
[engine] opt done Behavior>>new <split-7c8326a4> |AST 28|Tier 2|Time 208( 198+10 )ms|Inlined 5Y 0N|IR 265/ 588|CodeSize 1695|Addr 0x7ff8ab90bd10|Src n/a
[engine] opt done Variable class>>new |AST 26|Tier 2|Time 276( 254+23 )ms|Inlined 11Y 0N|IR 551/ 1233|CodeSize 3859|Addr 0x7ff8ab90d510|Src n/a
[engine] opt done Behavior>>new <split-187eb9a8> |AST 26|Tier 2|Time 52( 48+5 )ms|Inlined 0Y 0N|IR 80/ 143|CodeSize 482|Addr 0x7ff8ab90f790|Src n/a
[engine] opt done BinaryConstraint>>initializeVar:var:strength:addTo: |AST 34|Tier 2|Time 202( 175+26 )ms|Inlined 8Y 0N|IR 508/ 824|CodeSize 2453|Addr 0x7ff8ab910b90|Src n/a
[engine] opt done Variable>>addConstraint: |AST 24|Tier 2|Time 253( 140+113 )ms|Inlined 1Y 0N|IR 741/ 3182|CodeSize 10957|Addr 0x7ff8ab912890|Src n/a
[engine] opt done Vector>>forEach: <split-5b78fdb1> |AST 79|Tier 2|Time 149( 105+44 )ms|Inlined 2Y 0N|IR 246/ 2172|CodeSize 8132|Addr 0x7ff8ab919010|Src n/a
[engine] opt done Vector>>forEach: <split-2c779e5> |AST 79|Tier 2|Time 334( 192+142 )ms|Inlined 3Y 0N|IR 1003/ 3997|CodeSize 16699|Addr 0x7ff8ab91c510|Src n/a
[engine] opt done AbstractConstraint>>initialize: |AST 25|Tier 2|Time 166( 157+9 )ms|Inlined 7Y 0N|IR 371/ 386|CodeSize 1256|Addr 0x7ff8ab924a10|Src n/a
[engine] opt deopt Vector>>forEach: <split-5cbb84b1> |AST 79|Src n/a
[engine] opt deopt Vector>>forEach: <split-2c779e5> |AST 79|Src n/a
[engine] opt deopt BinaryConstraint>>chooseMethod: |AST 241|Src n/a
[engine] opt deopt BinaryConstraint>>output |AST 20|Src n/a
[engine] opt done Vector>>forEach: <split-6a84bc2a> |AST 79|Tier 2|Time 625( 312+314 )ms|Inlined 15Y 0N|IR 1829/ 5715|CodeSize 22216|Addr 0x7ff8ab927b90|Src n/a
[engine] opt done BinaryConstraint>>output |AST 20|Tier 2|Time 42( 37+5 )ms|Inlined 0Y 0N|IR 100/ 147|CodeSize 540|Addr 0x7ff8ab934c10|Src n/a
[engine] opt done BinaryConstraint>>chooseMethod: |AST 241|Tier 2|Time 175( 140+35 )ms|Inlined 4Y 0N|IR 701/ 1314|CodeSize 4831|Addr 0x7ff8ab935490|Src n/a
[engine] opt done Planner>>addPropagate:mark: <split-11653e3b> |AST 135|Tier 2|Time 555( 478+77 )ms|Inlined 24Y 0N|IR 1418/ 1145|CodeSize 3547|Addr 0x7ff8ab938410|Src n/a
[engine] opt done BinaryConstraint>>markUnsatisfied |AST 10|Tier 2|Time 31( 26+4 )ms|Inlined 0Y 0N|IR 80/ 253|CodeSize 734|Addr 0x7ff8ab93c610|Src n/a
[engine] opt done AbstractConstraint>>initialize: |AST 25|Tier 2|Time 166( 157+9 )ms|Inlined 7Y 0N|IR 383/ 392|CodeSize 1269|Addr 0x7ff8ab93ce90|Src n/a
[engine] opt done Vector>>forEach: <split-2c779e5> |AST 79|Tier 2|Time 326( 176+150 )ms|Inlined 3Y 0N|IR 1079/ 4101|CodeSize 17263|Addr 0x7ff8ab93db90|Src n/a
[engine] opt done Vector class>>new: |AST 29|Tier 2|Time 107( 89+19 )ms|Inlined 2Y 0N|IR 290/ 608|CodeSize 1869|Addr 0x7ff8ab946990|Src n/a
[engine] opt done Vector>>initialize: |AST 30|Tier 2|Time 67( 57+10 )ms|Inlined 0Y 0N|IR 205/ 444|CodeSize 1359|Addr 0x7ff8ab948490|Src n/a
[engine] opt done Behavior>>new <split-187eb9a8> |AST 26|Tier 2|Time 54( 48+6 )ms|Inlined 0Y 0N|IR 125/ 169|CodeSize 575|Addr 0x7ff8ab949290|Src n/a
[engine] opt done Symbol>>= <split-baf1bb3> |AST 28|Tier 2|Time 50( 47+3 )ms|Inlined 0Y 0N|IR 58/ 48|CodeSize 280|Addr 0x7ff8ab94a090|Src n/a
[engine] opt done ScaleConstraint>>execute |AST 152|Tier 2|Time 133( 122+11 )ms|Inlined 3Y 0N|IR 389/ 413|CodeSize 1344|Addr 0x7ff8ab94a590|Src n/a
[engine] opt done Plan>>execute <split-4bdcaf36> |AST 20|Tier 2|Time 128( 116+12 )ms|Inlined 4Y 0N|IR 474/ 400|CodeSize 1465|Addr 0x7ff8ab94b690|Src n/a
[engine] opt done Vector>>forEach: <split-332f25c8> |AST 79|Tier 2|Time 385( 196+189 )ms|Inlined 3Y 0N|IR 1055/ 3815|CodeSize 15602|Addr 0x7ff8ab950710|Src n/a
[engine] opt done Vector>>forEach: <split-61d01788> |AST 79|Tier 2|Time 277( 154+123 )ms|Inlined 5Y 0N|IR 659/ 2754|CodeSize 11182|Addr 0x7ff8ab95b890|Src n/a
[engine] opt deopt Vector>>forEach: <split-6a84bc2a> |AST 79|Src n/a
[engine] opt done Vector>>forEach: <split-6443b128> |AST 79|Tier 2|Time 374( 239+135 )ms|Inlined 3Y 0N|IR 1073/ 3884|CodeSize 15756|Addr 0x7ff8ab961190|Src n/a
[engine] opt done ScaleConstraint>>recalculate |AST 181|Tier 2|Time 227( 183+43 )ms|Inlined 9Y 0N|IR 758/ 791|CodeSize 2535|Addr 0x7ff8ab96a890|Src n/a
[engine] opt done Vector>>forEach: <split-264c5d07> |AST 79|Tier 2|Time 194( 109+85 )ms|Inlined 1Y 0N|IR 396/ 3533|CodeSize 13280|Addr 0x7ff8ab96df90|Src n/a
[engine] opt done Planner>>constraintsConsuming:do: |AST 62|Tier 2|Time 617( 425+192 )ms|Inlined 24Y 0N|IR 2475/ 4118|CodeSize 15701|Addr 0x7ff8ab973590|Src n/a
[engine] opt inv. EqualityConstraint class>>var:var:strength:addTo: |AST 40|Calls/Thres 12000/ 3|CallsAndLoop/Thres 12000/ 1000|Src n/a|Reason validRootAssumption Split call node
[engine] opt inv. EqualityConstraint>>initializeVar:var:strength:addTo: |AST 45|Calls/Thres 12000/ 3|CallsAndLoop/Thres 12000/ 1000|Src n/a|Reason validRootAssumption Split call node
[engine] opt done Vector>>forEach: <split-5d39f2d8> |AST 79|Tier 2|Time 371( 178+193 )ms|Inlined 3Y 0N|IR 941/ 3910|CodeSize 15504|Addr 0x7ff8ab980490|Src n/a
[engine] opt done Vector>>forEach: <split-6a84bc2a> |AST 79|Tier 2|Time 861( 454+407 )ms|Inlined 25Y 0N|IR 2717/ 6530|CodeSize 26280|Addr 0x7ff8ab988b90|Src n/a
[engine] opt deopt Vector>>forEach: <split-332f25c8> |AST 79|Src n/a
[engine] opt done Planner>>makePlan: |AST 212|Tier 2|Time 1196( 572+625 )ms|Inlined 24Y 0N|IR 2813/ 5537|CodeSize 28657|Addr 0x7ff8ab99b090|Src n/a
[engine] opt done Planner>>addConstraintsConsuming:to: <split-40021799> |AST 61|Tier 2|Time 221( 155+66 )ms|Inlined 2Y 0N|IR 916/ 1845|CodeSize 7786|Addr 0x7ff8ab9ae990|Src n/a
[engine] opt done Plan>>execute <split-1d207fad> |AST 20|Tier 2|Time 97( 89+9 )ms|Inlined 4Y 0N|IR 364/ 246|CodeSize 807|Addr 0x7ff8ab9b3510|Src n/a
[engine] opt done Vector>>forEach: <split-72ed9aad> |AST 79|Tier 2|Time 200( 129+72 )ms|Inlined 5Y 0N|IR 550/ 3061|CodeSize 12010|Addr 0x7ff8ab9b4190|Src n/a
[engine] opt done AbstractConstraint>>inputsKnown: |AST 52|Tier 2|Time 185( 182+3 )ms|Inlined 0Y 0N|IR 80/ 99|CodeSize 298|Addr 0x7ff8ab9b8f10|Src n/a
[engine] opt done EqualityConstraint>>initializeVar:var:strength:addTo: |AST 45|Tier 2|Time 1930(1083+847 )ms|Inlined 57Y 0N|IR 4712/ 9254|CodeSize 33444|Addr 0x7ff8ab9c6090|Src n/a
[engine] opt done EqualityConstraint class>>var:var:strength:addTo: |AST 40|Tier 2|Time 2166(1104+1062)ms|Inlined 59Y 0N|IR 4696/ 9449|CodeSize 36328|Addr 0x7ff8ab9e5010|Src n/a
[engine] opt done Variable class>>value: |AST 41|Tier 2|Time 211( 183+28 )ms|Inlined 13Y 0N|IR 655/ 1312|CodeSize 4090|Addr 0x7ff8aba0da90|Src n/a
[engine] opt deopt Vector>>forEach: <split-5d39f2d8> |AST 79|Src n/a
[engine] opt done Planner>>constraintsConsuming:do: |AST 57|Tier 2|Time 936( 452+484 )ms|Inlined 26Y 0N|IR 2773/ 4661|CodeSize 18120|Addr 0x7ff8aba1b090|Src n/a
[engine] opt done Vector>>forEach: <split-5d39f2d8> |AST 79|Tier 2|Time 327( 185+143 )ms|Inlined 3Y 0N|IR 1122/ 3868|CodeSize 15860|Addr 0x7ff8aba2c310|Src n/a
[engine] opt done Planner>>addPropagate:mark: <split-60921b21> |AST 131|Tier 2|Time 636( 546+90 )ms|Inlined 23Y 0N|IR 1503/ 1369|CodeSize 4219|Addr 0x7ff8aba34590|Src n/a
[engine] opt done Vector>>at: |AST 39|Tier 2|Time 151( 148+4 )ms|Inlined 0Y 0N|IR 74/ 111|CodeSize 546|Addr 0x7ff8aba39e10|Src n/a
[engine] opt done Vector>>forEach: <split-1dab9dd6> |AST 79|Tier 2|Time 140( 95+45 )ms|Inlined 2Y 0N|IR 246/ 2172|CodeSize 8130|Addr 0x7ff8aba3a790|Src n/a
[engine] opt done Planner>>removePropagateFrom: |AST 211|Tier 2|Time 2359( 848+1511)ms|Inlined 50Y 0N|IR 5825/20737|CodeSize 83568|Addr 0x7ff8aba3f210|Src n/a
[engine] opt deopt Planner>>makePlan: |AST 212|Src n/a
[engine] opt done Planner>>addPropagate:mark: <split-6f099cef> |AST 131|Tier 2|Time 497( 420+77 )ms|Inlined 24Y 0N|IR 1428/ 1145|CodeSize 3547|Addr 0x7ff8aba66010|Src n/a
[engine] opt done Vector>>forEach: <split-2f879bab> |AST 79|Tier 2|Time 159( 108+51 )ms|Inlined 2Y 0N|IR 325/ 2399|CodeSize 8900|Addr 0x7ff8aba70190|Src n/a
[engine] opt done AbstractConstraint>>inputsKnown: |AST 37|Tier 2|Time 250( 236+14 )ms|Inlined 5Y 0N|IR 350/ 342|CodeSize 998|Addr 0x7ff8aba74c10|Src n/a
[engine] opt done Vector>>forEach: <split-332f25c8> |AST 79|Tier 2|Time 467( 194+273 )ms|Inlined 3Y 0N|IR 1130/ 3999|CodeSize 17169|Addr 0x7ff8aba78990|Src n/a
[engine] opt done Vector>>forEach: <split-4690f583> |AST 79|Tier 2|Time 143( 99+44 )ms|Inlined 2Y 0N|IR 246/ 2172|CodeSize 8132|Addr 0x7ff8aba81b10|Src n/a
[engine] opt done BinaryConstraint>>inputsHasOne: |AST 59|Tier 2|Time 137( 100+37 )ms|Inlined 3Y 0N|IR 397/ 2277|CodeSize 7353|Addr 0x7ff8aba85010|Src n/a
[engine] opt done Symbol>>= <split-6d467c87> |AST 28|Tier 2|Time 41( 38+3 )ms|Inlined 0Y 0N|IR 58/ 48|CodeSize 280|Addr 0x7ff8aba88290|Src n/a
[engine] opt done Planner>>addConstraintsConsuming:to: <split-1b32cd16> |AST 57|Tier 2|Time 379( 212+168 )ms|Inlined 4Y 0N|IR 1176/ 2334|CodeSize 9801|Addr 0x7ff8aba88c90|Src n/a
[engine] opt done Planner>>addPropagate:mark: <split-46c00568> |AST 131|Tier 2|Time 678( 595+83 )ms|Inlined 24Y 0N|IR 1506/ 1247|CodeSize 3815|Addr 0x7ff8aba93410|Src n/a
[engine] opt done Planner>>makePlan: |AST 212|Tier 2|Time 1169( 709+460 )ms|Inlined 24Y 0N|IR 2885/ 5219|CodeSize 27376|Addr 0x7ff8aba98f90|Src n/a
[engine] opt done Vector>>forEach: <split-6df7988f> |AST 79|Tier 2|Time 318( 173+145 )ms|Inlined 3Y 0N|IR 941/ 3910|CodeSize 15504|Addr 0x7ff8abaadb90|Src n/a
[engine] opt done Planner class>>chainTest: |AST 461|Tier 2|Time 3880(2356+1523)ms|Inlined 71Y 5N|IR 5191/15016|CodeSize 63960|Addr 0x7ff8abab5d90|Src n/a
[engine] opt done Vector>>forEach: <split-3a095ec0> |AST 79|Tier 2|Time 329( 184+145 )ms|Inlined 3Y 0N|IR 941/ 3910|CodeSize 15504|Addr 0x7ff8abae9390|Src n/a
[engine] opt done Planner>>addPropagate:mark: <split-47b2e9e1> |AST 131|Tier 2|Time 587( 511+76 )ms|Inlined 24Y 0N|IR 1428/ 1145|CodeSize 3547|Addr 0x7ff8abaf1090|Src n/a
[engine] opt done Vector>>forEach: <split-37a64f9d> |AST 79|Tier 2|Time 188( 100+88 )ms|Inlined 2Y 0N|IR 246/ 4608|CodeSize 16808|Addr 0x7ff8abaf5b10|Src n/a
[engine] opt done Planner>>addPropagate:mark: <split-25b865b5> |AST 131|Tier 2|Time 608( 335+273 )ms|Inlined 15Y 0N|IR 872/ 1283|CodeSize 4212|Addr 0x7ff8abafd610|Src n/a
[engine] opt done UnaryConstraint>>chooseMethod: |AST 60|Tier 2|Time 79( 69+10 )ms|Inlined 1Y 0N|IR 232/ 313|CodeSize 1063|Addr 0x7ff8abb03e90|Src n/a
[engine] opt done UnaryConstraint>>inputsDo: |AST 6|Tier 2|Time 16( 14+2 )ms|Inlined 0Y 0N|IR 19/ 22|CodeSize 154|Addr 0x7ff8abb05d10|Src n/a
[engine] opt done UnaryConstraint>>addToGraph |AST 27|Tier 2|Time 269( 139+130 )ms|Inlined 2Y 0N|IR 861/ 3383|CodeSize 10921|Addr 0x7ff8abb08210|Src n/a
[engine] opt done Planner>>incrementalAdd: <split-6090f3ca> |AST 72|Tier 2|Time 1694(1286+409 )ms|Inlined 56Y 1N|IR 3644/ 4702|CodeSize 18732|Addr 0x7ff8abb0f590|Src n/a
[engine] opt done Symbol>>= <split-2d84cb86> |AST 28|Tier 2|Time 39( 36+3 )ms|Inlined 0Y 0N|IR 58/ 48|CodeSize 280|Addr 0x7ff8abb25f90|Src n/a
[engine] opt done ScaleConstraint>>inputsDo: |AST 136|Tier 2|Time 239( 143+96 )ms|Inlined 13Y 0N|IR 887/ 5977|CodeSize 19723|Addr 0x7ff8abb26490|Src n/a
[engine] opt done Behavior>>new <split-773bd77b> |AST 26|Tier 2|Time 47( 44+3 )ms|Inlined 0Y 0N|IR 33/ 99|CodeSize 416|Addr 0x7ff8abb2de10|Src n/a
[engine] opt done Planner class>>projectionTest: |AST 527|Tier 2|Time 2987(2069+918 )ms|Inlined 81Y 6N|IR 3822/18347|CodeSize 75805|Addr 0x7ff8abb2e410|Src n/a
[engine] opt done Planner>>addPropagate:mark: <split-55ea2d70> |AST 131|Tier 2|Time 695( 624+71 )ms|Inlined 23Y 0N|IR 1350/ 1153|CodeSize 3404|Addr 0x7ff8abb58a90|Src n/a
[engine] opt done AbstractConstraint>>addConstraint: <split-72805168> |AST 35|Tier 2|Time 2293(1308+985 )ms|Inlined 58Y 1N|IR 6025/11869|CodeSize 47067|Addr 0x7ff8abb5c490|Src n/a
[engine] opt done ScaleConstraint>>addToGraph |AST 78|Tier 2|Time 926( 311+615 )ms|Inlined 8Y 0N|IR 2804/12419|CodeSize 46098|Addr 0x7ff8abb81390|Src n/a
[engine] opt done StayConstraint class>>var:strength:addTo: |AST 37|Tier 2|Time 2868(1557+1310)ms|Inlined 72Y 2N|IR 5137/ 9222|CodeSize 35715|Addr 0x7ff8abb9a590|Src n/a
[engine] opt done Planner>>addPropagate:mark: <split-66bfd864> |AST 143|Tier 2|Time 1042( 548+494 )ms|Inlined 28Y 0N|IR 2672/ 4310|CodeSize 20892|Addr 0x7ff8abbc9590|Src n/a
[engine] opt done BinaryConstraint>>isSatisfied <split-458544e0> |AST 17|Tier 2|Time 39( 36+3 )ms|Inlined 0Y 0N|IR 32/ 56|CodeSize 333|Addr 0x7ff8abbd7810|Src n/a
[engine] opt done AbstractConstraint>>satisfy:propagate: <split-56ccd751> |AST 212|Tier 2|Time 1137( 894+243 )ms|Inlined 46Y 0N|IR 3524/ 5105|CodeSize 19370|Addr 0x7ff8abbd7d90|Src n/a
[engine] opt done Planner>>incrementalAdd: <split-1796bc36> |AST 72|Tier 2|Time 2042(1236+807 )ms|Inlined 69Y 1N|IR 4785/ 8638|CodeSize 37033|Addr 0x7ff8abbe6a90|Src n/a
[engine] opt done AbstractConstraint>>satisfy:propagate: <split-59942b48> |AST 165|Tier 2|Time 915( 648+267 )ms|Inlined 41Y 0N|IR 2808/ 2939|CodeSize 10870|Addr 0x7ff8abc02790|Src n/a
[engine] opt done Planner>>addConstraintsConsuming:to: <split-4f20a5e0> |AST 57|Tier 2|Time 368( 209+159 )ms|Inlined 4Y 0N|IR 987/ 2219|CodeSize 9703|Addr 0x7ff8abc0c710|Src n/a
[engine] opt done Planner>>addPropagate:mark: <split-1917d90f> |AST 143|Tier 2|Time 1053( 703+350 )ms|Inlined 28Y 0N|IR 2672/ 4310|CodeSize 20892|Addr 0x7ff8abc14410|Src n/a
[engine] opt done BinaryConstraint>>isSatisfied <split-71c905a3> |AST 17|Tier 2|Time 45( 42+3 )ms|Inlined 0Y 0N|IR 32/ 56|CodeSize 333|Addr 0x7ff8abc22690|Src n/a
[engine] opt done AbstractConstraint>>satisfy:propagate: <split-4e868ef5> |AST 151|Tier 2|Time 953( 791+162 )ms|Inlined 39Y 0N|IR 2654/ 2784|CodeSize 9856|Addr 0x7ff8abc22c10|Src n/a
[engine] opt done Planner>>incrementalAdd: <split-2fe88a09> |AST 72|Tier 2|Time 1647(1193+454 )ms|Inlined 66Y 0N|IR 4175/ 4842|CodeSize 21995|Addr 0x7ff8abc2c490|Src n/a
[engine] opt done Vector>>forEach: <split-5e742e4> |AST 79|Tier 2|Time 115( 86+29 )ms|Inlined 1Y 0N|IR 201/ 1281|CodeSize 4440|Addr 0x7ff8abc42610|Src n/a
[engine] opt done Strength class>>SymDefault |AST 10|Tier 2|Time 25( 23+2 )ms|Inlined 0Y 0N|IR 21/ 29|CodeSize 207|Addr 0x7ff8abc44890|Src n/a
[engine] opt done UnaryConstraint>>initializeVar:strength:addTo: <split-6872f9c8>|AST 45|Tier 2|Time 2527(1478+1050)ms|Inlined 54Y 1N|IR 4582/ 9917|CodeSize 35930|Addr 0x7ff8abc45210|Src n/a
[engine] opt done Behavior>>new <split-6f8f8a80> |AST 26|Tier 2|Time 54( 48+6 )ms|Inlined 0Y 0N|IR 92/ 278|CodeSize 999|Addr 0x7ff8abc63990|Src n/a
[engine] opt done AbstractConstraint>>addConstraint: <split-6d2d99fc> |AST 35|Tier 2|Time 2192(1272+920 )ms|Inlined 66Y 1N|IR 4819/ 8643|CodeSize 31423|Addr 0x7ff8abc64210|Src n/a
[engine] opt done Planner>>addConstraintsConsuming:to: <split-30af7377> |AST 57|Tier 2|Time 137( 117+20 )ms|Inlined 2Y 0N|IR 229/ 429|CodeSize 1465|Addr 0x7ff8abc85010|Src n/a
[engine] opt done Planner>>addConstraintsConsuming:to: <split-67a056f1> |AST 22|Tier 2|Time 31( 29+2 )ms|Inlined 0Y 0N|IR 27/ 29|CodeSize 198|Addr 0x7ff8abc86a10|Src n/a
[engine] opt done BinaryConstraint>>isSatisfied <split-68e62ca4> |AST 17|Tier 2|Time 38( 34+4 )ms|Inlined 0Y 0N|IR 75/ 114|CodeSize 415|Addr 0x7ff8abc87790|Src n/a
[engine] opt done Planner>>removePropagateFrom: |AST 49|Tier 2|Time 670( 541+129 )ms|Inlined 22Y 0N|IR 2170/ 3013|CodeSize 11307|Addr 0x7ff8abc87f10|Src n/a
[engine] opt done Planner>>addConstraintsConsuming:to: <split-69cac930> |AST 61|Tier 2|Time 219( 156+63 )ms|Inlined 2Y 0N|IR 923/ 1829|CodeSize 7619|Addr 0x7ff8abc8fc90|Src n/a
[engine] opt done Vector>>forEach: <split-1eea9d2d> |AST 79|Tier 2|Time 306( 177+129 )ms|Inlined 3Y 0N|IR 1055/ 3005|CodeSize 13169|Addr 0x7ff8abc94890|Src n/a
[engine] opt done Planner>>removePropagateFrom: |AST 41|Tier 2|Time 208( 143+64 )ms|Inlined 2Y 0N|IR 867/ 1819|CodeSize 8070|Addr 0x7ff8abc9c910|Src n/a
[engine] opt done ScaleConstraint class>>var:var:var:var:strength:addTo: |AST 46|Tier 2|Time 3494(1470+2024)ms|Inlined 65Y 3N|IR 7609/18336|CodeSize 76723|Addr 0x7ff8abca1590|Src n/a
[engine] opt done Planner>>addPropagate:mark: <split-7c974942> |AST 143|Tier 2|Time 1167( 677+490 )ms|Inlined 27Y 1N|IR 3014/ 5711|CodeSize 19996|Addr 0x7ff8abce2d10|Src n/a
[engine] opt done ScaleConstraint>>initializeSrc:scale:offset:dst:strength:addTo:|AST 55|Tier 2|Time 3064(1366+1699)ms|Inlined 62Y 4N|IR 7605/18624|CodeSize 76421|Addr 0x7ff8abcf4710|Src n/a
[engine] opt done BinaryConstraint>>isSatisfied <split-511d5d04> |AST 17|Tier 2|Time 42( 38+4 )ms|Inlined 0Y 0N|IR 75/ 114|CodeSize 415|Addr 0x7ff8abd2cf10|Src n/a
[engine] opt done BinaryConstraint>>isSatisfied <split-4a1c0752> |AST 17|Tier 2|Time 46( 41+4 )ms|Inlined 0Y 0N|IR 75/ 114|CodeSize 415|Addr 0x7ff8abd2d910|Src n/a
[engine] opt done BinaryConstraint>>addToGraph |AST 44|Tier 2|Time 644( 389+255 )ms|Inlined 4Y 0N|IR 1470/ 6608|CodeSize 22419|Addr 0x7ff8abd2e990|Src n/a
[engine] opt done Planner>>addPropagate:mark: <split-201aa8c1> |AST 143|Tier 2|Time 1284( 795+489 )ms|Inlined 28Y 0N|IR 2851/ 4055|CodeSize 18933|Addr 0x7ff8abd3b610|Src n/a
[engine] opt done Planner>>incrementalAdd: <split-3e9803c2> |AST 72|Tier 2|Time 2623(1365+1258)ms|Inlined 57Y 10N|IR 6683/12250|CodeSize 50974|Addr 0x7ff8abd49e90|Src n/a
[engine] opt done Planner>>incrementalAdd: <split-695a69a1> |AST 72|Tier 2|Time 3300(1404+1896)ms|Inlined 79Y 2N|IR 7780/17856|CodeSize 71962|Addr 0x7ff8abd74a10|Src n/a
[engine] opt done Planner>>addConstraintsConsuming:to: <split-5eeedb60> |AST 57|Tier 2|Time 383( 229+154 )ms|Inlined 4Y 0N|IR 1119/ 2201|CodeSize 9399|Addr 0x7ff8abda5b90|Src n/a
[engine] opt done BinaryConstraint>>isSatisfied <split-19593091> |AST 18|Tier 2|Time 150( 146+4 )ms|Inlined 0Y 0N|IR 93/ 102|CodeSize 395|Addr 0x7ff8abdae090|Src n/a
[engine] opt done UnaryConstraint>>initializeVar:strength:addTo: |AST 45|Tier 2|Time 3224(1641+1583)ms|Inlined 59Y 5N|IR 6670/11515|CodeSize 49959|Addr 0x7ff8abdae790|Src n/a
[engine] opt done AbstractConstraint>>addConstraint: <split-6b580b88> |AST 35|Tier 2|Time 3407(1664+1742)ms|Inlined 69Y 4N|IR 7710/13557|CodeSize 61860|Addr 0x7ff8abddfc10|Src n/a
[engine] opt done BinaryConstraint>>isSatisfied |AST 18|Tier 2|Time 39( 36+4 )ms|Inlined 0Y 0N|IR 93/ 102|CodeSize 395|Addr 0x7ff8abe1c010|Src n/a
[engine] opt done AbstractConstraint>>satisfy:propagate: |AST 183|Tier 2|Time 1605( 796+809 )ms|Inlined 48Y 0N|IR 4613/ 7322|CodeSize 26716|Addr 0x7ff8abe1fb10|Src n/a
[engine] opt done BinaryConstraint>>isSatisfied <split-6579c3d9> |AST 17|Tier 2|Time 37( 34+3 )ms|Inlined 0Y 0N|IR 32/ 56|CodeSize 333|Addr 0x7ff8abe37710|Src n/a
[engine] opt done AbstractConstraint>>satisfy:propagate: <split-1136b469> |AST 165|Tier 2|Time 1603( 883+719 )ms|Inlined 42Y 0N|IR 3679/ 6636|CodeSize 24095|Addr 0x7ff8abe37c90|Src n/a
[engine] opt done Integer>>timesRepeat: |AST 46|Tier 2|Time 502( 189+313 )ms|Inlined 9Y 0N|IR 903/ 3179|CodeSize 12314|Addr 0x7ff8abe4e690|Src n/a
[engine] opt done AbstractConstraint>>addConstraint: |AST 39|Tier 2|Time 2486(1350+1135)ms|Inlined 43Y 5N|IR 7010/18167|CodeSize 73643|Addr 0x7ff8abe56d10|Src n/a
[engine] opt done Vector>>remove: |AST 97|Tier 2|Time 259( 191+68 )ms|Inlined 3Y 0N|IR 597/ 1591|CodeSize 6436|Addr 0x7ff8abe80810|Src n/a
[engine] opt done UnaryConstraint>>removeFromGraph |AST 37|Tier 2|Time 308( 214+94 )ms|Inlined 5Y 0N|IR 734/ 1774|CodeSize 6897|Addr 0x7ff8abe85010|Src n/a
[engine] opt done Variable>>removeConstraint: |AST 39|Tier 2|Time 308( 225+83 )ms|Inlined 4Y 0N|IR 653/ 1690|CodeSize 7059|Addr 0x7ff8abe8a590|Src n/a
[engine] opt done Vector>>forEach: <split-47a3341c> |AST 79|Tier 2|Time 287( 162+125 )ms|Inlined 2Y 0N|IR 890/ 2929|CodeSize 11513|Addr 0x7ff8abe8f710|Src n/a
[engine] opt done String class>>new: |AST 51|Tier 2|Time 106( 94+12 )ms|Inlined 2Y 1N|IR 213/ 474|CodeSize 1885|Addr 0x7ff8abe96490|Src n/a
[engine] opt done UnaryConstraint>>markUnsatisfied |AST 10|Tier 2|Time 29( 26+3 )ms|Inlined 0Y 0N|IR 95/ 90|CodeSize 286|Addr 0x7ff8abe97c10|Src n/a
[engine] opt done SmallInteger>>printString |AST 152|Tier 2|Time 221( 203+18 )ms|Inlined 4Y 1N|IR 362/ 377|CodeSize 1188|Addr 0x7ff8abe98890|Src n/a
[engine] opt done Vector>>forEach: <split-6079cf5> |AST 79|Tier 2|Time 361( 209+152 )ms|Inlined 3Y 0N|IR 1055/ 3850|CodeSize 15754|Addr 0x7ff8abe99a90|Src n/a
[engine] opt done Vector class>>new |AST 20|Tier 2|Time 105( 88+18 )ms|Inlined 3Y 0N|IR 317/ 489|CodeSize 1181|Addr 0x7ff8abea2490|Src n/a
[engine] opt done Vector>>forEach: <split-1e6308a9> |AST 79|Tier 2|Time 313( 176+137 )ms|Inlined 3Y 0N|IR 941/ 3839|CodeSize 15530|Addr 0x7ff8abea3990|Src n/a
[engine] opt done Planner>>extractPlanFromConstraints: |AST 59|Tier 2|Time 1365( 693+672 )ms|Inlined 32Y 0N|IR 3601/ 6870|CodeSize 36814|Addr 0x7ff8abeab890|Src n/a
[engine] opt done Strength class>>SymPreferred |AST 10|Tier 2|Time 29( 27+2 )ms|Inlined 0Y 0N|IR 21/ 29|CodeSize 207|Addr 0x7ff8abeca710|Src n/a
[engine] Truffle runtime statistics for engine 3
Compilations : 194
Success : 185
Temporary Bailouts : 4
org.graalvm.compiler.core.common.RetryableBailoutException: Assumption invalidated while compiling code: Object[Instance<org.graalvm.compiler.truffle.runtime.OptimizedAssumption>]: 3
org.graalvm.compiler.core.common.CancellationBailoutException: Compilation cancelled.: 1
Permanent Bailouts : 0
Failed : 0
Interrupted : 5
Invalidated : 11
validRootAssumption Split call node : 5
Profiled Argument Types : 3
frame version : 2
Latest layout assumption Layout no longer valid: 1
Queues : 287
Dequeues : 101
Target inlined into only caller : 93
Split call node : 5
validRootAssumption Split call node : 2
frame version : 1
Splits : 516
Compilation Accuracy : 0.943299
Queue Accuracy : 0.648084
Compilation Utilization : 1.721789
Remaining Compilation Queue : 0
Time to queue : count= 287, sum= 1050951, min= 2, average= 3661.85, max= 56327 (milliseconds), maxTarget=Strength class>>SymPreferred
Time waiting in queue : count= 194, sum= 912173, min= 0, average= 4701.92, max= 31349 (milliseconds), maxTarget=BinaryConstraint>>isSatisfied <split-6579c3d9>
Time for compilation : count= 189, sum= 100550, min= 16, average= 532.01, max= 3880 (milliseconds), maxTarget=Planner class>>chainTest:
Truffle Tier : count= 185, sum= 60991, min= 14, average= 329.68, max= 2356 (milliseconds), maxTarget=Planner class>>chainTest:
Graal Tier : count= 185, sum= 26439, min= 1, average= 142.92, max= 1450 (milliseconds), maxTarget=Planner>>incrementalAdd: <split-695a69a1>
Code Installation : count= 185, sum= 12211, min= 1, average= 66.01, max= 698 (milliseconds), maxTarget=ScaleConstraint class>>var:var:var:var:strength:addTo:
Truffle node count : count= 189, sum= 196881, min= 7, average= 1041.70, max= 6247, maxTarget=Planner>>incrementalAdd: <split-695a69a1>
Trivial : count= 189, sum= 77726, min= 1, average= 411.25, max= 2440, maxTarget=Planner>>incrementalAdd: <split-695a69a1>
Non Trivial : count= 189, sum= 119155, min= 6, average= 630.45, max= 3807, maxTarget=Planner>>incrementalAdd: <split-695a69a1>
Monomorphic : count= 189, sum= 115058, min= 6, average= 608.77, max= 3667, maxTarget=Planner>>incrementalAdd: <split-695a69a1>
Polymorphic : count= 189, sum= 4097, min= 0, average= 21.68, max= 171, maxTarget=AbstractConstraint>>addConstraint: <split-6b580b88>
Megamorphic : count= 189, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Behavior>>new <split-187eb9a8>
Truffle call count : count= 189, sum= 2430, min= 0, average= 12.86, max= 87, maxTarget=Planner class>>projectionTest:
Indirect : count= 189, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Behavior>>new <split-187eb9a8>
Direct : count= 189, sum= 2430, min= 0, average= 12.86, max= 87, maxTarget=Planner class>>projectionTest:
Dispatched : count= 189, sum= 55, min= 0, average= 0.29, max= 10, maxTarget=Planner>>incrementalAdd: <split-3e9803c2>
Inlined : count= 189, sum= 2375, min= 0, average= 12.57, max= 81, maxTarget=Planner class>>projectionTest:
---------- :
Cloned : count= 189, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Behavior>>new <split-187eb9a8>
Not Cloned : count= 189, sum= 2368, min= 0, average= 12.53, max= 87, maxTarget=Planner class>>projectionTest:
Truffle loops : count= 189, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Behavior>>new <split-187eb9a8>
Graal node count :
After Truffle Tier : count= 189, sum= 228730, min= 5, average= 1210.21, max= 7780, maxTarget=Planner>>incrementalAdd: <split-695a69a1>
After Graal Tier : count= 188, sum= 512669, min= 5, average= 2726.96, max= 20737, maxTarget=Planner>>removePropagateFrom:
Graal compilation result :
Code size : count= 185, sum= 2044547, min= 120, average= 11051.61, max= 83568, maxTarget=Planner>>removePropagateFrom:
Total frame size : count= 185, sum= 42480, min= 32, average= 229.62, max= 960, maxTarget=ScaleConstraint>>initializeSrc:scale:offset:dst:strength:addTo:
Exception handlers : count= 185, sum= 224, min= 0, average= 1.21, max= 19, maxTarget=AbstractConstraint>>addConstraint:
Infopoints : count= 185, sum= 20069, min= 4, average= 108.48, max= 736, maxTarget=ScaleConstraint>>initializeSrc:scale:offset:dst:strength:addTo:
CALL : count= 185, sum= 15467, min= 4, average= 83.61, max= 571, maxTarget=AbstractConstraint>>addConstraint:
IMPLICIT_EXCEPTION : count= 185, sum= 4216, min= 0, average= 22.79, max= 174, maxTarget=Planner>>incrementalAdd: <split-695a69a1>
SAFEPOINT : count= 185, sum= 386, min= 0, average= 2.09, max= 17, maxTarget=Planner>>removePropagateFrom:
Marks : count= 185, sum= 1729, min= 5, average= 9.35, max= 31, maxTarget=AbstractConstraint>>addConstraint:
Data references : count= 185, sum= 17235, min= 0, average= 93.16, max= 1366, maxTarget=Planner class>>projectionTest:
Raw
b5e925dc-Havlak.trace.log
# Havlak (iterations: 250, problem size: 1500) on Mon Jul 26 08:22:16 UTC 2021
# `/home/fniephaus/bin/graalvm-ee-java11-21.2.0/bin/trufflesqueak --experimental-options --smalltalk.disable-startup --smalltalk.disable-interrupts --engine.Mode=default --engine.MultiTier=false --engine.DynamicCompilationThresholds=false --engine.TraceCompilation --engine.CompilationStatistics --log.file="/home/fniephaus/dev/are-we-fast-yet/traces/Havlak.trace.log" --quiet --code "FileStream startUp: true. Harness new run: #(nil 'Havlak' 250 1500)" /home/fniephaus/dev/are-we-fast-yet/benchmarks/Smalltalk/AWFY64-sista-fbc.image`
[engine] opt done Behavior>>new <split-7c8326a4> |AST 26|Tier 2|Time 153( 136+16 )ms|Inlined 0Y 0N|IR 33/ 99|CodeSize 416|Addr 0x7f99235db910|Src n/a
[engine] opt done Vector class>>new: |AST 29|Tier 2|Time 205( 195+11 )ms|Inlined 2Y 0N|IR 57/ 366|CodeSize 1218|Addr 0x7f99235e4f90|Src n/a
[engine] opt done Vector>>at: |AST 39|Tier 2|Time 70( 62+7 )ms|Inlined 0Y 0N|IR 144/ 219|CodeSize 749|Addr 0x7f99235f2f90|Src n/a
[engine] opt done Vector>>size |AST 18|Tier 2|Time 44( 41+3 )ms|Inlined 0Y 0N|IR 32/ 112|CodeSize 434|Addr 0x7f99235f4c10|Src n/a
[engine] opt done Integer>>>> |AST 31|Tier 2|Time 53( 50+3 )ms|Inlined 0Y 0N|IR 39/ 113|CodeSize 445|Addr 0x7f9923600d10|Src n/a
[engine] opt done SomDictionary>>hash: |AST 60|Tier 2|Time 96( 92+4 )ms|Inlined 1Y 0N|IR 33/ 108|CodeSize 419|Addr 0x7f9923607490|Src n/a
[engine] opt done SomDictionary>>bucketIdx: |AST 40|Tier 2|Time 86( 75+11 )ms|Inlined 0Y 0N|IR 91/ 213|CodeSize 753|Addr 0x7f9923608810|Src n/a
[engine] opt done DictIdEntry>>match:key: |AST 29|Tier 2|Time 57( 53+4 )ms|Inlined 0Y 0N|IR 43/ 73|CodeSize 375|Addr 0x7f9923612710|Src n/a
[engine] opt done HavlakLoopFinder>>Unvisited |AST 8|Tier 2|Time 50( 47+3 )ms|Inlined 0Y 0N|IR 18/ 54|CodeSize 260|Addr 0x7f9923618a10|Src n/a
[engine] opt done Vector class>>new |AST 20|Tier 2|Time 139( 133+7 )ms|Inlined 3Y 0N|IR 49/ 292|CodeSize 1023|Addr 0x7f992361c510|Src n/a
[engine] opt done Vector>>append: |AST 163|Tier 2|Time 214( 165+49 )ms|Inlined 0Y 0N|IR 452/ 1713|CodeSize 7549|Addr 0x7f9923625090|Src n/a
[engine] opt done SomDictionary>>bucket: |AST 33|Tier 2|Time 89( 84+5 )ms|Inlined 1Y 0N|IR 155/ 117|CodeSize 587|Addr 0x7f992362f190|Src n/a
[engine] opt done Vector>>isEmpty |AST 18|Tier 2|Time 41( 39+2 )ms|Inlined 0Y 0N|IR 31/ 48|CodeSize 257|Addr 0x7f9923639590|Src n/a
[engine] opt done SomDictionary>>at: |AST 86|Tier 2|Time 174( 166+8 )ms|Inlined 5Y 0N|IR 237/ 245|CodeSize 1010|Addr 0x7f992363a610|Src n/a
[engine] opt done HavlakLoopFinder>>isAncestor:v: |AST 41|Tier 2|Time 66( 61+5 )ms|Inlined 0Y 0N|IR 103/ 203|CodeSize 741|Addr 0x7f992363bb90|Src n/a
[engine] opt done Vector>>forEach: <split-5395ea39> |AST 79|Tier 2|Time 902( 524+378 )ms|Inlined 18Y 0N|IR 2080/ 6559|CodeSize 28574|Addr 0x7f9923652590|Src n/a
[engine] opt done Vector>>hasSome: <split-1517f633> |AST 79|Tier 2|Time 317( 86+231 )ms|Inlined 1Y 0N|IR 215/ 4162|CodeSize 14381|Addr 0x7f9923669f90|Src n/a
[engine] opt done UnionFindNode>>initialize |AST 10|Tier 2|Time 30( 27+3 )ms|Inlined 0Y 0N|IR 68/ 79|CodeSize 238|Addr 0x7f9923671e10|Src n/a
[engine] opt deopt Vector>>at: |AST 39|Src n/a
[engine] opt done HavlakLoopFinder>>doDFS:current: |AST 226|Tier 2|Time 2033( 955+1079)ms|Inlined 53Y 1N|IR 3051/ 7737|CodeSize 38182|Addr N/A|Src n/a
[engine] opt inv. HavlakLoopFinder>>doDFS:current: |AST 226|Calls/Thres 16500/ 3|CallsAndLoop/Thres 40500/ 1000|Src n/a|Reason frame version
[engine] opt inv. Vector>>forEach: <split-5395ea39> |AST 79|Calls/Thres 10226/ 3|CallsAndLoop/Thres 25102/ 1000|Src n/a|Reason Latest layout assumption Layout no longer valid
[engine] opt inv. SomDictionary>>at: |AST 86|Calls/Thres 7303/ 3|CallsAndLoop/Thres 7303/ 1000|Src n/a|Reason Latest layout assumption Layout no longer valid
[engine] opt inv. DictIdEntry>>match:key: |AST 30|Calls/Thres 4386/ 3|CallsAndLoop/Thres 4386/ 1000|Src n/a|Reason Latest layout assumption Layout no longer valid
[engine] opt done Vector>>at: |AST 39|Tier 2|Time 59( 51+8 )ms|Inlined 0Y 0N|IR 149/ 277|CodeSize 1012|Addr 0x7f99236ac390|Src n/a
[engine] opt done DictIdEntry>>match:key: |AST 30|Tier 2|Time 34( 32+2 )ms|Inlined 0Y 0N|IR 35/ 51|CodeSize 245|Addr 0x7f99236b6110|Src n/a
[engine] opt done SomSet class>>new |AST 28|Tier 2|Time 120( 103+17 )ms|Inlined 5Y 0N|IR 261/ 634|CodeSize 1813|Addr 0x7f99236b6590|Src n/a
[engine] opt done Vector>>forEach: <split-13d186db> |AST 79|Tier 2|Time 1104( 514+590 )ms|Inlined 22Y 0N|IR 2485/ 8396|CodeSize 36932|Addr 0x7f99236bc310|Src n/a
[engine] opt deopt Vector>>forEach: <split-13d186db> |AST 79|Src n/a
[engine] opt deopt Vector>>hasSome: <split-1517f633> |AST 79|Src n/a
[engine] opt done Vector>>forEach: <split-5395ea39> |AST 79|Tier 2|Time 1051( 483+567 )ms|Inlined 18Y 0N|IR 2168/ 6727|CodeSize 28785|Addr 0x7f99236e3e10|Src n/a
[engine] opt done DictIdEntry>>match:key: |AST 30|Tier 2|Time 45( 41+4 )ms|Inlined 0Y 0N|IR 75/ 81|CodeSize 429|Addr 0x7f99236f8d90|Src n/a
[engine] opt done Vector>>hasSome: <split-1517f633> |AST 79|Tier 2|Time 167( 85+83 )ms|Inlined 1Y 0N|IR 245/ 4409|CodeSize 15400|Addr 0x7f99236fa290|Src n/a
[engine] opt done HavlakLoopFinder>>doDFS:current: |AST 226|Tier 2|Time 2380(1257+1123)ms|Inlined 68Y 1N|IR 3674/ 9238|CodeSize 49090|Addr 0x7f9923700a90|Src n/a
[engine] opt done SomDictionary>>at: |AST 86|Tier 2|Time 138( 131+7 )ms|Inlined 5Y 0N|IR 287/ 245|CodeSize 1010|Addr 0x7f992372bb90|Src n/a
[engine] opt done Vector>>hasSome: <split-466d49f0> |AST 81|Tier 2|Time 167( 84+83 )ms|Inlined 1Y 0N|IR 245/ 4409|CodeSize 15218|Addr 0x7f992372e590|Src n/a
[engine] opt done Object>>= |AST 14|Tier 2|Time 30( 26+4 )ms|Inlined 0Y 0N|IR 76/ 135|CodeSize 481|Addr 0x7f9923735110|Src n/a
[engine] opt done HavlakLoopFinder>>findLoops |AST 760|Tier 2|Time 5207(1831+3376)ms|Inlined 61Y 11N|IR 11429/41700|CodeSize 178356|Addr 0x7f9923735810|Src n/a
[engine] opt done Object>>~= <split-7b60c3e> |AST 26|Tier 2|Time 47( 45+2 )ms|Inlined 1Y 0N|IR 27/ 36|CodeSize 230|Addr 0x7f9923785f10|Src n/a
[engine] opt done HavlakLoopFinder>>stepEProcessNonBackPreds:nodePool:workList:x:|AST 14|Tier 2|Time 28( 26+2 )ms|Inlined 0Y 0N|IR 25/ 27|CodeSize 183|Addr 0x7f9923786a10|Src n/a
[engine] opt done Vector>>forEach: <split-13d186db> |AST 79|Tier 2|Time 1757( 828+929 )ms|Inlined 26Y 0N|IR 3595/11761|CodeSize 58203|Addr 0x7f9923787210|Src n/a
[engine] opt done UnionFindNode>>findSet |AST 156|Tier 2|Time 495( 314+181 )ms|Inlined 12Y 0N|IR 954/ 2138|CodeSize 9527|Addr 0x7f99237ae110|Src n/a
[engine] opt done Vector>>forEach: <split-6569dded> |AST 79|Tier 2|Time 124( 94+29 )ms|Inlined 2Y 0N|IR 266/ 1384|CodeSize 5098|Addr 0x7f99237b4d90|Src n/a
[engine] opt done SomIdentitySet>>contains: |AST 25|Tier 2|Time 137( 110+27 )ms|Inlined 3Y 0N|IR 315/ 460|CodeSize 1610|Addr 0x7f99237b8410|Src n/a
[engine] opt deopt SomIdentitySet>>contains: |AST 25|Src n/a
[engine] opt done Vector>>forEach: <split-2d7e1102> |AST 79|Tier 2|Time 768( 470+297 )ms|Inlined 15Y 0N|IR 1643/ 4959|CodeSize 22454|Addr 0x7f99237ba610|Src n/a
[engine] opt done SomIdentitySet>>contains: |AST 25|Tier 2|Time 142( 114+28 )ms|Inlined 3Y 0N|IR 315/ 446|CodeSize 1672|Addr 0x7f99237c9d90|Src n/a
[engine] opt done SomSet>>hasSome: <split-349d0836> |AST 23|Tier 2|Time 310( 91+219 )ms|Inlined 2Y 0N|IR 296/ 4516|CodeSize 15514|Addr 0x7f99237cc210|Src n/a
[engine] opt done SomDictionary>>transferEntries: |AST 194|Tier 2|Time 262( 195+66 )ms|Inlined 3Y 0N|IR 815/ 1708|CodeSize 7578|Addr 0x7f99237d3510|Src n/a
[engine] opt done Vector>>forEach: <split-6a74d228> |AST 79|Tier 2|Time 1020( 382+637 )ms|Inlined 17Y 0N|IR 2176/ 6571|CodeSize 29255|Addr 0x7f99237d8110|Src n/a
[engine] opt deopt Vector>>forEach: <split-6a74d228> |AST 79|Src n/a
[engine] opt done UnionFindNode class>>new |AST 26|Tier 2|Time 72( 68+3 )ms|Inlined 3Y 0N|IR 33/ 100|CodeSize 416|Addr 0x7f99237ee190|Src n/a
[engine] opt done Vector>>forEach: <split-6a74d228> |AST 79|Tier 2|Time 1075( 523+552 )ms|Inlined 17Y 0N|IR 2176/ 6770|CodeSize 30817|Addr 0x7f99237efa90|Src n/a
[engine] opt done SomIdentityDictionary>>newEntry:value:hash: |AST 31|Tier 2|Time 88( 85+4 )ms|Inlined 3Y 0N|IR 51/ 112|CodeSize 445|Addr 0x7f9923806210|Src n/a
[engine] opt done HavlakLoopFinder>>identifyEdges: |AST 128|Tier 2|Time 1389( 510+879 )ms|Inlined 22Y 0N|IR 2505/ 6928|CodeSize 33839|Addr 0x7f9923806890|Src n/a
[engine] opt done Behavior>>new <split-35c09b94> |AST 26|Tier 2|Time 95( 47+48 )ms|Inlined 0Y 0N|IR 33/ 99|CodeSize 416|Addr 0x7f9923826a90|Src n/a
[engine] opt done DictIdEntry class>>new:key:value:next: |AST 40|Tier 2|Time 127( 123+4 )ms|Inlined 2Y 0N|IR 50/ 112|CodeSize 432|Addr 0x7f9923827790|Src n/a
[engine] opt done Vector>>forEach: <split-4482469c> |AST 79|Tier 2|Time 274( 163+111 )ms|Inlined 2Y 0N|IR 686/ 2831|CodeSize 11262|Addr 0x7f9923827e10|Src n/a
[engine] opt done SomSet>>size |AST 20|Tier 2|Time 52( 48+4 )ms|Inlined 1Y 0N|IR 46/ 130|CodeSize 519|Addr 0x7f992382e610|Src n/a
[engine] opt done Vector>>removeFirst |AST 53|Tier 2|Time 81( 76+6 )ms|Inlined 1Y 0N|IR 124/ 166|CodeSize 634|Addr 0x7f992382f710|Src n/a
[engine] opt done HavlakLoopFinder>>MaxNonBackPreds |AST 14|Tier 2|Time 26( 24+2 )ms|Inlined 0Y 0N|IR 18/ 54|CodeSize 260|Addr 0x7f9923830090|Src n/a
[engine] opt done HavlakLoopFinder>>stepD:nodePool: |AST 45|Tier 2|Time 874( 414+459 )ms|Inlined 17Y 0N|IR 1817/ 4337|CodeSize 20136|Addr 0x7f9923830790|Src n/a
[engine] opt done HavlakLoopFinder>>stepEProcessNonBackPreds:nodePool:workList:x:|AST 58|Tier 2|Time 2222( 798+1425)ms|Inlined 29Y 0N|IR 4008/13429|CodeSize 63338|Addr 0x7f9923843510|Src n/a
[engine] opt done SomSet>>forEach: |AST 24|Tier 2|Time 1827( 803+1024)ms|Inlined 27Y 0N|IR 3640/11841|CodeSize 58249|Addr 0x7f9923870690|Src n/a
[engine] opt done HavlakLoopFinder>>findLoops <split-ddf20fd> |AST 760|Tier 2|Time 5992(2107+3885)ms|Inlined 70Y 9N|IR 12463/44034|CodeSize 187170|Addr 0x7f992389d990|Src n/a
[engine] opt done HavlakLoopFinder>>initAllNodes <split-67ec8477> |AST 34|Tier 2|Time 755( 560+195 )ms|Inlined 22Y 0N|IR 1758/ 3488|CodeSize 15157|Addr 0x7f9923902290|Src n/a
[engine] opt done Symbol>>= <split-3703bf3c> |AST 26|Tier 2|Time 40( 38+2 )ms|Inlined 0Y 0N|IR 27/ 39|CodeSize 217|Addr 0x7f992390ec10|Src n/a
[engine] opt done DictEntry>>init:key:value:next: |AST 26|Tier 2|Time 35( 31+4 )ms|Inlined 0Y 0N|IR 136/ 283|CodeSize 739|Addr 0x7f992390f090|Src n/a
[engine] opt done UnionFindNode>>union: |AST 11|Tier 2|Time 28( 25+3 )ms|Inlined 0Y 0N|IR 60/ 166|CodeSize 431|Addr 0x7f992390f910|Src n/a
[engine] opt done Vector>>forEach: <split-278f8425> |AST 79|Tier 2|Time 999( 586+413 )ms|Inlined 23Y 0N|IR 1923/ 7067|CodeSize 35171|Addr 0x7f9923910090|Src n/a
[engine] opt done SimpleLoop>>counter: |AST 11|Tier 2|Time 32( 30+3 )ms|Inlined 0Y 0N|IR 74/ 90|CodeSize 256|Addr 0x7f9923924410|Src n/a
[engine] opt done Behavior>>new <split-2e3a5237> |AST 28|Tier 2|Time 68( 64+3 )ms|Inlined 1Y 0N|IR 33/ 100|CodeSize 416|Addr 0x7f9923924a90|Src n/a
[engine] opt done HavlakLoopFinder>>setLoopAttribute:nodePool:loop: |AST 137|Tier 2|Time 786( 359+428 )ms|Inlined 16Y 0N|IR 1947/ 4458|CodeSize 18459|Addr 0x7f9923925a90|Src n/a
[engine] opt done SimpleLoop class>>basicBlock:reducible: <split-5b78fdb1> |AST 34|Tier 2|Time 632( 455+177 )ms|Inlined 20Y 0N|IR 1310/ 3372|CodeSize 13058|Addr 0x7f9923937e90|Src n/a
[engine] opt inv. HavlakLoopFinder>>findLoops <split-ddf20fd> |AST 760|Calls/Thres 33/ 3|CallsAndLoop/Thres 516153/ 1000|Src n/a|Reason Profiled Argument Types
[engine] opt inv. HavlakLoopFinder>>findLoops |AST 760|Calls/Thres 1501/ 3|CallsAndLoop/Thres 63641/ 1000|Src n/a|Reason Profiled Argument Types
[engine] opt deopt SimpleLoop class>>basicBlock:reducible: <split-5b78fdb1> |AST 34|Src n/a
[engine] opt deopt LoopStructureGraph>>createNewLoop:reducible: |AST 80|Src n/a
[engine] opt done LoopStructureGraph>>createNewLoop:reducible: |AST 80|Tier 2|Time 813( 516+297 )ms|Inlined 23Y 0N|IR 1891/ 5151|CodeSize 21649|Addr 0x7f9923941710|Src n/a
[engine] opt done SimpleLoop class>>basicBlock:reducible: <split-5b78fdb1> |AST 34|Tier 2|Time 667( 482+186 )ms|Inlined 20Y 0N|IR 1310/ 3524|CodeSize 14332|Addr 0x7f99234cce10|Src n/a
[engine] opt done LoopStructureGraph>>createNewLoop:reducible: |AST 80|Tier 2|Time 878( 568+309 )ms|Inlined 23Y 0N|IR 1891/ 5297|CodeSize 21998|Addr 0x7f992395b110|Src n/a
[engine] opt done Vector>>forEach: <split-13d186db> |AST 79|Tier 2|Time 2684(1053+1631)ms|Inlined 46Y 1N|IR 4845/18348|CodeSize 82159|Addr 0x7f992396a990|Src n/a
[engine] opt done SomSet>>forEach: |AST 24|Tier 2|Time 2998(1070+1927)ms|Inlined 47Y 1N|IR 4909/18622|CodeSize 81952|Addr 0x7f99239a4790|Src n/a
[engine] opt done SomSet>>add: <split-715fb77> |AST 40|Tier 2|Time 279( 192+87 )ms|Inlined 5Y 0N|IR 793/ 1886|CodeSize 7791|Addr 0x7f99239e5090|Src n/a
[engine] opt done Behavior>>new <split-48bfb884> |AST 26|Tier 2|Time 49( 43+6 )ms|Inlined 0Y 0N|IR 94/ 376|CodeSize 1303|Addr 0x7f99234cb990|Src n/a
[engine] opt done SimpleLoop>>init:reducible: <split-11653e3b> |AST 93|Tier 2|Time 621( 338+283 )ms|Inlined 18Y 0N|IR 1231/ 3023|CodeSize 12753|Addr 0x7f99239ea810|Src n/a
[engine] opt done UnionFindNode>>loop: |AST 11|Tier 2|Time 28( 25+3 )ms|Inlined 0Y 0N|IR 60/ 167|CodeSize 431|Addr 0x7f99239f2690|Src n/a
[engine] opt done Object>>~= <split-34cf5a97> |AST 26|Tier 2|Time 52( 49+3 )ms|Inlined 1Y 0N|IR 95/ 57|CodeSize 295|Addr 0x7f99239f3210|Src n/a
[engine] opt done Symbol>>= <split-5b3f3ba0> |AST 28|Tier 2|Time 39( 37+3 )ms|Inlined 0Y 0N|IR 58/ 48|CodeSize 278|Addr 0x7f99239f3710|Src n/a
[engine] opt done SimpleLoop>>addNode: |AST 24|Tier 2|Time 315( 196+119 )ms|Inlined 6Y 0N|IR 856/ 1991|CodeSize 8330|Addr 0x7f99239f3c10|Src n/a
[engine] opt done HavlakLoopFinder>>setLoopAttribute:nodePool:loop: |AST 60|Tier 2|Time 1218( 562+656 )ms|Inlined 19Y 0N|IR 2288/ 5926|CodeSize 25970|Addr 0x7f99239fad90|Src n/a
[engine] opt deopt HavlakLoopFinder>>setLoopAttribute:nodePool:loop: |AST 60|Src n/a
[engine] opt done SomSet>>add: <split-57bd802b> |AST 40|Tier 2|Time 284( 191+93 )ms|Inlined 5Y 0N|IR 801/ 1942|CodeSize 8399|Addr 0x7f9923a13d10|Src n/a
[engine] opt done SomSet>>add: <split-3e9803c2> |AST 40|Tier 2|Time 363( 277+86 )ms|Inlined 5Y 0N|IR 793/ 1886|CodeSize 7791|Addr 0x7f9923a19e90|Src n/a
[engine] opt done HavlakLoopFinder>>setLoopAttribute:nodePool:loop: |AST 60|Tier 2|Time 1318( 488+829 )ms|Inlined 19Y 0N|IR 2288/ 6084|CodeSize 28469|Addr 0x7f9923a21310|Src n/a
[engine] opt done HavlakLoopFinder>>findLoops <split-ddf20fd> |AST 760|Tier 2|Time 4546(2157+2389)ms|Inlined 59Y 7N|IR 9883/35991|CodeSize 154505|Addr 0x7f9923a3d290|Src n/a
[engine] opt done SomDictionary>>insertBucketEntry:value:hash:head: |AST 122|Tier 2|Time 284( 272+12 )ms|Inlined 7Y 0N|IR 317/ 484|CodeSize 1273|Addr 0x7f9923a89d90|Src n/a
[engine] opt done HavlakLoopFinder>>stepEProcessNonBackPreds:nodePool:workList:x:|AST 213|Tier 2|Time 1272( 615+657 )ms|Inlined 25Y 0N|IR 3331/10922|CodeSize 54502|Addr 0x7f9923a8fa10|Src n/a
[engine] opt done SomSet>>add: <split-3e9803c2> |AST 40|Tier 2|Time 281( 189+92 )ms|Inlined 5Y 0N|IR 793/ 2038|CodeSize 9059|Addr 0x7f9923ab1b90|Src n/a
[engine] opt done HavlakLoopFinder>>findLoops |AST 23|Tier 2|Time 201( 139+62 )ms|Inlined 1Y 0N|IR 527/ 1771|CodeSize 7682|Addr 0x7f9923ab8990|Src n/a
[engine] opt done ControlFlowGraph>>numNodes |AST 20|Tier 2|Time 110( 106+4 )ms|Inlined 1Y 0N|IR 46/ 130|CodeSize 519|Addr 0x7f9923abdc90|Src n/a
[engine] opt done UnionFindNode>>initNode:dfs: |AST 20|Tier 2|Time 34( 29+5 )ms|Inlined 0Y 0N|IR 105/ 204|CodeSize 702|Addr 0x7f9923abe310|Src n/a
[engine] opt done Vector>>forEach: <split-4bdcaf36> |AST 79|Tier 2|Time 331( 165+166 )ms|Inlined 2Y 0N|IR 675/ 2831|CodeSize 11327|Addr 0x7f9923abed10|Src n/a
[engine] opt done DictEntry>>value: |AST 11|Tier 2|Time 31( 28+3 )ms|Inlined 0Y 0N|IR 73/ 90|CodeSize 256|Addr 0x7f9923ac6010|Src n/a
[engine] opt done SomDictionary>>at:put: <split-38499e48> |AST 115|Tier 2|Time 277( 256+21 )ms|Inlined 11Y 0N|IR 508/ 747|CodeSize 2358|Addr 0x7f9923ac6690|Src n/a
[engine] opt done Vector>>forEach: <split-61d01788> |AST 79|Tier 2|Time 361( 272+89 )ms|Inlined 10Y 0N|IR 616/ 3070|CodeSize 11954|Addr 0x7f9923ac8c90|Src n/a
[engine] opt done SimpleLoop>>addChildLoop: |AST 24|Tier 2|Time 317( 205+112 )ms|Inlined 6Y 0N|IR 848/ 1935|CodeSize 7950|Addr 0x7f9923ace210|Src n/a
[engine] opt done SimpleLoop>>parent: |AST 28|Tier 2|Time 362( 218+144 )ms|Inlined 7Y 0N|IR 913/ 2063|CodeSize 8263|Addr 0x7f9923ad4610|Src n/a
[engine] opt done Vector>>at:put: |AST 201|Tier 2|Time 175( 120+55 )ms|Inlined 0Y 0N|IR 483/ 1905|CodeSize 8575|Addr 0x7f9923adb910|Src n/a
[engine] opt done HavlakLoopFinder>>findLoops <split-1b32cd16> |AST 760|Tier 2|Time 3476(1756+1720)ms|Inlined 47Y 7N|IR 8480/32314|CodeSize 138304|Addr 0x7f9923adfe10|Src n/a
[engine] opt done Vector>>hasSome: <split-599f571f> |AST 81|Tier 2|Time 110( 82+29 )ms|Inlined 1Y 0N|IR 250/ 1288|CodeSize 4828|Addr 0x7f9923b1e190|Src n/a
[engine] opt done SimpleLoop>>addChildLoop: |AST 24|Tier 2|Time 398( 275+124 )ms|Inlined 6Y 0N|IR 848/ 2087|CodeSize 9071|Addr 0x7f9923b21b10|Src n/a
[engine] opt done SomIdentitySet>>contains: |AST 14|Tier 2|Time 31( 28+2 )ms|Inlined 0Y 0N|IR 25/ 27|CodeSize 183|Addr 0x7f9923b28a10|Src n/a
[engine] opt done SimpleLoop>>parent: |AST 28|Tier 2|Time 424( 270+153 )ms|Inlined 7Y 0N|IR 913/ 2215|CodeSize 9075|Addr 0x7f9923b28e10|Src n/a
[engine] opt done SomSet>>contains: |AST 25|Tier 2|Time 148( 124+23 )ms|Inlined 3Y 0N|IR 318/ 456|CodeSize 1398|Addr 0x7f9923b31110|Src n/a
[engine] opt done SomSet>>hasSome: <split-301d8120> |AST 23|Tier 2|Time 138( 103+36 )ms|Inlined 2Y 0N|IR 301/ 1351|CodeSize 4841|Addr 0x7f9923b33190|Src n/a
[engine] opt done SomSet>>add: <split-6d367020> |AST 40|Tier 2|Time 289( 200+89 )ms|Inlined 5Y 0N|IR 804/ 2097|CodeSize 8581|Addr 0x7f9923b35e10|Src n/a
[engine] opt done HavlakLoopFinder>>processEdges:w: |AST 125|Tier 2|Time 633( 403+229 )ms|Inlined 17Y 0N|IR 1991/ 4686|CodeSize 21047|Addr 0x7f9923b3c510|Src n/a
[engine] opt done BasicBlock>>addOutEdge: |AST 24|Tier 2|Time 188( 126+62 )ms|Inlined 1Y 0N|IR 524/ 1765|CodeSize 7865|Addr 0x7f9923b4ba10|Src n/a
[engine] opt done BasicBlock>>addInEdge: |AST 24|Tier 2|Time 245( 184+61 )ms|Inlined 1Y 0N|IR 524/ 1765|CodeSize 7865|Addr 0x7f9923b50310|Src n/a
[engine] opt done Vector>>forEach: <split-4b1abd11> |AST 79|Tier 2|Time 756( 375+380 )ms|Inlined 20Y 1N|IR 1376/ 2803|CodeSize 10483|Addr 0x7f9923b54c10|Src n/a
[engine] opt done BasicBlock class>>new: |AST 31|Tier 2|Time 159( 149+9 )ms|Inlined 8Y 0N|IR 76/ 689|CodeSize 2366|Addr 0x7f9923b61f90|Src n/a
[engine] opt done ControlFlowGraph>>addEdge: |AST 24|Tier 2|Time 198( 136+62 )ms|Inlined 1Y 0N|IR 524/ 1765|CodeSize 7865|Addr 0x7f9923b62f90|Src n/a
[engine] opt done SimpleLoop>>nestingLevel: |AST 34|Tier 2|Time 64( 59+5 )ms|Inlined 1Y 0N|IR 158/ 163|CodeSize 341|Addr 0x7f9923b67890|Src n/a
[engine] opt done Magnitude>>max: |AST 21|Tier 2|Time 33( 31+2 )ms|Inlined 0Y 0N|IR 53/ 34|CodeSize 206|Addr 0x7f9923b68e90|Src n/a
[engine] opt done Vector>>forEach: <split-67a056f1> |AST 79|Tier 2|Time 333( 219+114 )ms|Inlined 2Y 0N|IR 686/ 2831|CodeSize 11170|Addr 0x7f9923b69810|Src n/a
[engine] opt done Vector>>forEach: <split-6a4d7f76> |AST 79|Tier 2|Time 689( 294+395 )ms|Inlined 9Y 0N|IR 1206/ 5179|CodeSize 21847|Addr 0x7f9923b70190|Src n/a
[engine] opt done LoopStructureGraph>>calculateNestingLevel |AST 62|Tier 2|Time 520( 236+284 )ms|Inlined 8Y 0N|IR 1040/ 2178|CodeSize 8662|Addr 0x7f9923b7e890|Src n/a
[engine] opt done HavlakLoopFinder>>initAllNodes <split-71c905a3> |AST 34|Tier 2|Time 681( 481+200 )ms|Inlined 22Y 0N|IR 1758/ 3488|CodeSize 15156|Addr 0x7f9923b89310|Src n/a
[engine] opt done Vector>>forEach: <split-79767781> |AST 79|Tier 2|Time 1019( 477+541 )ms|Inlined 23Y 0N|IR 1923/ 7067|CodeSize 34944|Addr 0x7f9923b95990|Src n/a
[engine] opt done SimpleLoop>>depthLevel: |AST 11|Tier 2|Time 30( 27+3 )ms|Inlined 0Y 0N|IR 74/ 90|CodeSize 256|Addr 0x7f9923baa290|Src n/a
[engine] opt done LoopStructureGraph>>calculateNestingLevelRec:depth: |AST 58|Tier 2|Time 947( 521+426 )ms|Inlined 23Y 1N|IR 1482/ 1952|CodeSize 7483|Addr 0x7f9923baa910|Src n/a
[engine] opt done SomSet>>forEach: <split-332f25c8> |AST 24|Tier 2|Time 840( 359+481 )ms|Inlined 21Y 1N|IR 1414/ 2843|CodeSize 10319|Addr 0x7f9923bba210|Src n/a
[engine] opt done Symbol>>= <split-1dab9dd6> |AST 26|Tier 2|Time 40( 38+2 )ms|Inlined 0Y 0N|IR 27/ 39|CodeSize 217|Addr 0x7f9923bc8790|Src n/a
[engine] opt done HavlakLoopFinder>>initAllNodes |AST 34|Tier 2|Time 124( 122+2 )ms|Inlined 5Y 0N|IR 5/ 5|CodeSize 120|Addr 0x7f9923bc8c10|Src n/a
[engine] opt done HavlakLoopFinder>>stepD:nodePool: |AST 55|Tier 2|Time 531( 342+188 )ms|Inlined 14Y 0N|IR 1456/ 3879|CodeSize 18142|Addr 0x7f9923bc9490|Src n/a
[engine] opt done HavlakLoopFinder class>>new:lsg: |AST 34|Tier 2|Time 327( 312+15 )ms|Inlined 14Y 0N|IR 273/ 1071|CodeSize 3673|Addr 0x7f9923bd5610|Src n/a
[engine] opt done LoopStructureGraph>>calculateNestingLevelRec:depth: |AST 85|Tier 2|Time 1431( 495+936 )ms|Inlined 23Y 1N|IR 1648/ 4118|CodeSize 15165|Addr 0x7f9923bd6c90|Src n/a
[engine] opt done LoopTesterApp>>addDummyLoops: |AST 21|Tier 2|Time 219( 196+23 )ms|Inlined 16Y 1N|IR 382/ 1413|CodeSize 4988|Addr 0x7f9923be8c10|Src n/a
[engine] opt done Object>>~= <split-247667dd> |AST 26|Tier 2|Time 57( 54+3 )ms|Inlined 1Y 0N|IR 95/ 57|CodeSize 295|Addr 0x7f9923bed610|Src n/a
[engine] opt done Object>>~= <split-1b9c1b51> |AST 26|Tier 2|Time 52( 50+3 )ms|Inlined 1Y 0N|IR 27/ 36|CodeSize 230|Addr 0x7f9923bedb10|Src n/a
[engine] opt done Vector>>removeAll |AST 37|Tier 2|Time 68( 61+7 )ms|Inlined 0Y 0N|IR 154/ 329|CodeSize 952|Addr 0x7f9923bee010|Src n/a
[engine] opt done HavlakLoopFinder>>findLoops <split-53dfacba> |AST 760|Tier 2|Time 4657(2113+2544)ms|Inlined 59Y 7N|IR 9883/35991|CodeSize 154491|Addr 0x7f9923beef10|Src n/a
[engine] opt done Integer>>timesRepeat: <split-10ec523c> |AST 46|Tier 2|Time 326( 260+66 )ms|Inlined 17Y 1N|IR 505/ 2685|CodeSize 10850|Addr 0x7f9923c3b990|Src n/a
[engine] opt done LoopTesterApp>>buildStraight:n: <split-55ea2d70> |AST 95|Tier 2|Time 1754( 602+1152)ms|Inlined 30Y 0N|IR 3071/ 9359|CodeSize 44308|Addr 0x7f9923c3fe90|Src n/a
[engine] opt done LoopTesterApp>>buildStraight:n: <split-5e742e4> |AST 95|Tier 2|Time 1957( 749+1207)ms|Inlined 30Y 0N|IR 3071/ 9359|CodeSize 44308|Addr 0x7f9923c6c490|Src n/a
[engine] opt done HavlakLoopFinder>>initAllNodes <split-6ad6fa53> |AST 64|Tier 2|Time 3074(1317+1757)ms|Inlined 80Y 1N|IR 4323/13743|CodeSize 72485|Addr 0x7f9923c96b90|Src n/a
[engine] opt done SomSet>>contains: |AST 15|Tier 2|Time 27( 25+2 )ms|Inlined 0Y 0N|IR 29/ 31|CodeSize 200|Addr 0x7f9923cd6210|Src n/a
[engine] opt done LoopTesterApp>>buildStraight:n: <split-175acfb2> |AST 95|Tier 2|Time 1836( 680+1156)ms|Inlined 29Y 0N|IR 2843/ 9092|CodeSize 42606|Addr 0x7f9923cd6910|Src n/a
[engine] opt done HavlakLoopFinder>>initAllNodes <split-588ffeb> |AST 34|Tier 2|Time 729( 564+165 )ms|Inlined 22Y 0N|IR 1664/ 2897|CodeSize 11599|Addr 0x7f9923cfff10|Src n/a
[engine] opt done LoopTesterApp>>buildStraight:n: <split-4a03bb68> |AST 95|Tier 2|Time 1778( 611+1167)ms|Inlined 29Y 0N|IR 2843/ 9092|CodeSize 42606|Addr 0x7f9923d0a510|Src n/a
[engine] opt done LoopTesterApp>>constructCFG:p:p: |AST 26|Tier 2|Time 408( 398+10 )ms|Inlined 0Y 1N|IR 107/ 483|CodeSize 1734|Addr 0x7f9923d35990|Src n/a
[engine] opt done Integer>>timesRepeat: <split-30135202> |AST 46|Tier 2|Time 830( 764+66 )ms|Inlined 1Y 1N|IR 242/ 2800|CodeSize 10220|Addr 0x7f9923d36e90|Src n/a
[engine] opt done BasicBlockEdge class>>for:from:to: <split-458544e0> |AST 37|Tier 2|Time 1499( 677+822 )ms|Inlined 28Y 0N|IR 2918/ 9110|CodeSize 43297|Addr 0x7f9923d3b110|Src n/a
[engine] opt done LoopTesterApp>>buildBaseLoop: <split-afb5821> |AST 181|Tier 2|Time 2761(2434+326 )ms|Inlined 25Y 9N|IR 1351/ 4455|CodeSize 18712|Addr 0x7f9923d5b990|Src n/a
[engine] opt done Behavior>>new <split-59712875> |AST 26|Tier 2|Time 46( 43+3 )ms|Inlined 0Y 0N|IR 33/ 99|CodeSize 416|Addr 0x7f9923d65890|Src n/a
[engine] opt done BasicBlockEdge class>>for:from:to: <split-4690f583> |AST 37|Tier 2|Time 1299( 532+767 )ms|Inlined 28Y 0N|IR 2918/ 9110|CodeSize 43297|Addr 0x7f9923d65e90|Src n/a
[engine] opt done BasicBlockEdge>>init:from:to: <split-3241713e> |AST 96|Tier 2|Time 704( 398+307 )ms|Inlined 16Y 0N|IR 2265/ 6133|CodeSize 28076|Addr 0x7f9923d86310|Src n/a
[engine] opt done Behavior>>new <split-18a645fd> |AST 26|Tier 2|Time 47( 44+3 )ms|Inlined 0Y 0N|IR 33/ 99|CodeSize 416|Addr 0x7f9923d98790|Src n/a
[engine] opt done BasicBlockEdge class>>for:from:to: <split-488b50ec> |AST 37|Tier 2|Time 1264( 529+735 )ms|Inlined 28Y 0N|IR 2918/ 9110|CodeSize 43297|Addr 0x7f9923d98d90|Src n/a
[engine] opt done BasicBlockEdge>>init:from:to: <split-6090f3ca> |AST 96|Tier 2|Time 1137( 641+495 )ms|Inlined 26Y 0N|IR 2889/ 9367|CodeSize 45619|Addr 0x7f9923db9690|Src n/a
[engine] opt done Behavior>>new <split-6872f9c8> |AST 26|Tier 2|Time 48( 45+3 )ms|Inlined 0Y 0N|IR 33/ 99|CodeSize 416|Addr 0x7f9923dd5a10|Src n/a
[engine] opt done LoopTesterApp>>buildDiamond: <split-56ccd751> |AST 157|Tier 2|Time 4658(1838+2821)ms|Inlined 66Y 4N|IR 8892/26183|CodeSize 130286|Addr 0x7f9923dd6010|Src n/a
[engine] opt done ControlFlowGraph>>createNode: <split-25b865b5> |AST 115|Tier 2|Time 570( 423+147 )ms|Inlined 14Y 0N|IR 997/ 3510|CodeSize 15458|Addr 0x7f9923e48190|Src n/a
[engine] opt done Behavior>>new <split-6df7988f> |AST 26|Tier 2|Time 49( 46+4 )ms|Inlined 0Y 0N|IR 33/ 99|CodeSize 416|Addr 0x7f9923e51890|Src n/a
[engine] opt done BasicBlockEdge class>>for:from:to: <split-1d782abe> |AST 37|Tier 2|Time 1280( 653+627 )ms|Inlined 28Y 0N|IR 2918/ 9110|CodeSize 43297|Addr 0x7f9923e51e90|Src n/a
[engine] opt done Behavior>>new <split-1917d90f> |AST 26|Tier 2|Time 50( 46+3 )ms|Inlined 0Y 0N|IR 33/ 99|CodeSize 416|Addr 0x7f9923e72890|Src n/a
[engine] opt done BasicBlockEdge class>>for:from:to: <split-2fe88a09> |AST 37|Tier 2|Time 1417( 700+717 )ms|Inlined 27Y 0N|IR 2685/ 8842|CodeSize 41213|Addr 0x7f9923e75190|Src n/a
[engine] opt done BasicBlockEdge>>init:from:to: <split-66bfd864> |AST 96|Tier 2|Time 1308( 711+598 )ms|Inlined 25Y 0N|IR 2659/ 8989|CodeSize 41954|Addr 0x7f9923e93890|Src n/a
[engine] opt done Behavior>>new <split-1e6308a9> |AST 26|Tier 2|Time 50( 46+3 )ms|Inlined 0Y 0N|IR 33/ 99|CodeSize 416|Addr 0x7f9923eadd10|Src n/a
[engine] opt done BasicBlockEdge>>init:from:to: <split-30cecdca> |AST 96|Tier 2|Time 1002( 530+472 )ms|Inlined 25Y 0N|IR 2659/ 8989|CodeSize 41954|Addr 0x7f9923eae310|Src n/a
[engine] opt done BasicBlockEdge class>>for:from:to: <split-6079cf5> |AST 37|Tier 2|Time 1117( 517+600 )ms|Inlined 27Y 0N|IR 2685/ 8842|CodeSize 41213|Addr 0x7f9923ec8790|Src n/a
[engine] opt done Behavior>>new <split-1440c311> |AST 26|Tier 2|Time 75( 72+3 )ms|Inlined 0Y 0N|IR 33/ 99|CodeSize 416|Addr 0x7f9923ee7110|Src n/a
[engine] opt done LoopTesterApp>>buildDiamond: <split-54da32dc> |AST 157|Tier 2|Time 4459(1743+2716)ms|Inlined 66Y 3N|IR 8762/25672|CodeSize 124193|Addr 0x7f9923ee7710|Src n/a
[engine] opt done ControlFlowGraph>>createNode: <split-60723d6a> |AST 76|Tier 2|Time 141( 120+21 )ms|Inlined 4Y 0N|IR 370/ 548|CodeSize 2075|Addr 0x7f9923f56010|Src n/a
[engine] opt done ControlFlowGraph>>createNode: <split-18324f97> |AST 76|Tier 2|Time 134( 113+20 )ms|Inlined 4Y 0N|IR 370/ 548|CodeSize 2075|Addr 0x7f9923f57d90|Src n/a
[engine] opt done ControlFlowGraph>>createNode: <split-44afefd5> |AST 76|Tier 2|Time 129( 109+20 )ms|Inlined 4Y 0N|IR 370/ 548|CodeSize 2075|Addr 0x7f9923f59b10|Src n/a
[engine] opt done ControlFlowGraph>>createNode: <split-9a7a808> |AST 76|Tier 2|Time 125( 105+20 )ms|Inlined 4Y 0N|IR 370/ 548|CodeSize 2075|Addr 0x7f9923f5b890|Src n/a
[engine] opt done BasicBlockEdge>>init:from:to: <split-2b97cc1f> |AST 96|Tier 2|Time 763( 374+388 )ms|Inlined 16Y 0N|IR 2265/ 6133|CodeSize 28076|Addr 0x7f9923f5d610|Src n/a
[engine] opt done ControlFlowGraph>>createNode: <split-5486887b> |AST 94|Tier 2|Time 483( 348+135 )ms|Inlined 13Y 0N|IR 787/ 3404|CodeSize 14193|Addr 0x7f9923f6fd10|Src n/a
[engine] opt done BasicBlockEdge>>init:from:to: <split-abbc908> |AST 96|Tier 2|Time 784( 386+398 )ms|Inlined 16Y 0N|IR 2265/ 6133|CodeSize 28076|Addr 0x7f9923f78410|Src n/a
[engine] opt done ControlFlowGraph>>createNode: <split-1c025cb> |AST 76|Tier 2|Time 138( 117+21 )ms|Inlined 4Y 0N|IR 370/ 548|CodeSize 2075|Addr 0x7f9923f8af90|Src n/a
[engine] opt done ControlFlowGraph>>createNode: <split-1869f114> |AST 115|Tier 2|Time 421( 278+143 )ms|Inlined 14Y 0N|IR 997/ 3510|CodeSize 15458|Addr 0x7f9923f8cd10|Src n/a
[engine] opt done ControlFlowGraph>>createNode: <split-50825a02> |AST 94|Tier 2|Time 484( 285+199 )ms|Inlined 13Y 0N|IR 787/ 3404|CodeSize 14193|Addr 0x7f9923f96c90|Src n/a
[engine] opt done ControlFlowGraph>>createNode: <split-5f84abe8> |AST 76|Tier 2|Time 209( 188+21 )ms|Inlined 4Y 0N|IR 370/ 548|CodeSize 2075|Addr 0x7f9923f9f390|Src n/a
[engine] opt done BasicBlockEdge>>init:from:to: <split-37c5fc56> |AST 96|Tier 2|Time 1096( 562+534 )ms|Inlined 25Y 0N|IR 2659/ 8989|CodeSize 41954|Addr 0x7f9923fa2490|Src n/a
[engine] opt done ControlFlowGraph>>createNode: <split-4650a407> |AST 94|Tier 2|Time 414( 275+139 )ms|Inlined 13Y 0N|IR 787/ 3404|CodeSize 14193|Addr 0x7f9923fbc910|Src n/a
[engine] opt done BasicBlockEdge>>init:from:to: <split-29be7749> |AST 96|Tier 2|Time 1092( 634+457 )ms|Inlined 25Y 0N|IR 2659/ 8989|CodeSize 41954|Addr 0x7f9923fc5010|Src n/a
[engine] opt done ControlFlowGraph>>createNode: <split-3a095ec0> |AST 76|Tier 2|Time 130( 110+20 )ms|Inlined 4Y 0N|IR 370/ 548|CodeSize 2075|Addr 0x7f9923fdf490|Src n/a
[engine] opt done ControlFlowGraph>>createNode: <split-7e8a46b7> |AST 115|Tier 2|Time 418( 275+143 )ms|Inlined 14Y 0N|IR 997/ 3510|CodeSize 15458|Addr 0x7f9923fe1210|Src n/a
[engine] opt done ControlFlowGraph>>createNode: <split-4f20a5e0> |AST 115|Tier 2|Time 531( 285+246 )ms|Inlined 14Y 0N|IR 997/ 3510|CodeSize 15458|Addr 0x7f9923fea910|Src n/a
[engine] opt done ControlFlowGraph>>createNode: <split-1d4664d7> |AST 76|Tier 2|Time 125( 105+20 )ms|Inlined 4Y 0N|IR 370/ 548|CodeSize 2075|Addr 0x7f9923ff4010|Src n/a
[engine] opt done BasicBlockEdge>>init:from:to: <split-52a36910> |AST 96|Tier 2|Time 1151( 655+496 )ms|Inlined 26Y 0N|IR 2889/ 9367|CodeSize 45619|Addr 0x7f9923ff5d90|Src n/a
[engine] opt done SomDictionary>>removeAll |AST 33|Tier 2|Time 65( 59+6 )ms|Inlined 0Y 0N|IR 129/ 307|CodeSize 922|Addr 0x7f9924012110|Src n/a
[engine] opt done ControlFlowGraph>>createNode: <split-46c00568> |AST 115|Tier 2|Time 435( 273+163 )ms|Inlined 14Y 0N|IR 997/ 3510|CodeSize 15458|Addr 0x7f9924012c10|Src n/a
[engine] opt done BasicBlockEdge>>init:from:to: <split-2f879bab> |AST 96|Tier 2|Time 1112( 611+501 )ms|Inlined 26Y 0N|IR 2889/ 9367|CodeSize 45619|Addr 0x7f992401c310|Src n/a
[engine] opt done Integer>>timesRepeat: <split-16c3ca31> |AST 46|Tier 2|Time 709( 670+38 )ms|Inlined 0Y 1N|IR 185/ 1691|CodeSize 5301|Addr 0x7f9924038a90|Src n/a
[engine] opt done LoopStructureGraph>>initialize |AST 113|Tier 2|Time 460( 338+121 )ms|Inlined 23Y 0N|IR 1340/ 3927|CodeSize 15674|Addr 0x7f992403b910|Src n/a
[engine] opt done LoopTesterApp>>buildStraight:n: <split-aced190> |AST 95|Tier 2|Time 1810( 685+1124)ms|Inlined 29Y 0N|IR 2843/ 9092|CodeSize 42606|Addr 0x7f9924044190|Src n/a
[engine] opt done LoopTesterApp>>buildStraight:n: <split-78411116> |AST 95|Tier 2|Time 1986( 756+1230)ms|Inlined 30Y 0N|IR 3071/ 9359|CodeSize 44308|Addr 0x7f992406cf10|Src n/a
[engine] opt done LoopTesterApp>>buildConnect:end: <split-56de6d6b> |AST 31|Tier 2|Time 1056( 423+633 )ms|Inlined 19Y 0N|IR 2356/ 6041|CodeSize 26440|Addr 0x7f9924097610|Src n/a
[engine] opt done Integer>>timesRepeat: |AST 46|Tier 2|Time 87( 54+33 )ms|Inlined 0Y 0N|IR 162/ 1823|CodeSize 5842|Addr 0x7f99240b1410|Src n/a
[engine] opt done SomSet>>add: <split-6d91790b> |AST 40|Tier 2|Time 302( 210+92 )ms|Inlined 5Y 0N|IR 804/ 2097|CodeSize 8581|Addr 0x7f99240b3b10|Src n/a
[engine] opt done LoopTesterApp>>findLoops: |AST 44|Tier 2|Time 217( 196+21 )ms|Inlined 15Y 1N|IR 344/ 1365|CodeSize 4840|Addr 0x7f99240ba510|Src n/a
[engine] opt done LoopStructureGraph class>>new |AST 26|Tier 2|Time 988( 819+169 )ms|Inlined 49Y 0N|IR 1668/ 4515|CodeSize 15859|Addr 0x7f99240be690|Src n/a
[engine] opt done LoopTesterApp>>main:loop:p:p:p: |AST 31|Tier 2|Time 1240( 961+279 )ms|Inlined 66Y 1N|IR 2026/ 5863|CodeSize 23104|Addr 0x7f99240c6d90|Src n/a
[engine] opt done LoopTesterApp>>constructCFG:p:p: <split-2d195ee4> |AST 106|Tier 2|Time 5942(2185+3758)ms|Inlined 71Y 3N|IR 8641/26141|CodeSize 124725|Addr 0x7f99240d2790|Src n/a
[engine] opt done HavlakLoopFinder>>initAllNodes |AST 64|Tier 2|Time 4563(1798+2765)ms|Inlined 85Y 3N|IR 5538/17773|CodeSize 94419|Addr 0x7f9924148990|Src n/a
[engine] opt done Integer>>timesRepeat: <split-6075b2d3> |AST 46|Tier 2|Time 1601( 995+606 )ms|Inlined 67Y 1N|IR 2148/10524|CodeSize 40680|Addr 0x7f99241a3610|Src n/a
[engine] opt done HavlakLoopFinder>>initAllNodes <split-44abe8b6> |AST 64|Tier 2|Time 4007(1499+2509)ms|Inlined 89Y 2N|IR 5611/17841|CodeSize 95977|Addr 0x7f99241b8090|Src n/a
[engine] opt done Integer>>timesRepeat: <split-997d532> |AST 46|Tier 2|Time 699( 647+52 )ms|Inlined 0Y 1N|IR 192/ 1711|CodeSize 5389|Addr 0x7f9924213c90|Src n/a
[engine] opt done ControlFlowGraph>>createNode: <split-7839586d> |AST 115|Tier 2|Time 508( 346+162 )ms|Inlined 14Y 0N|IR 982/ 3544|CodeSize 15561|Addr 0x7f9924216410|Src n/a
[engine] opt done LoopTesterApp>>buildConnect:end: <split-302e8219> |AST 31|Tier 2|Time 1086( 473+613 )ms|Inlined 19Y 0N|IR 2356/ 6041|CodeSize 26440|Addr 0x7f992421ff90|Src n/a
[engine] opt done BasicBlockEdge class>>for:from:to: <split-5d34b4de> |AST 37|Tier 2|Time 954( 404+550 )ms|Inlined 18Y 0N|IR 2296/ 5986|CodeSize 26198|Addr 0x7f992423d990|Src n/a
[engine] opt done LoopTesterApp>>buildConnect:end: <split-79267ac8> |AST 31|Tier 2|Time 1062( 414+648 )ms|Inlined 19Y 0N|IR 2356/ 6041|CodeSize 26440|Addr 0x7f9924253490|Src n/a
[engine] opt done LoopTesterApp>>constructCFG:p:p: <split-3c247814> |AST 115|Tier 2|Time 3668(2064+1604)ms|Inlined 55Y 1N|IR 6026/19659|CodeSize 87239|Addr 0x7f992426d290|Src n/a
[engine] opt done String class>>new: |AST 51|Tier 2|Time 181( 159+22 )ms|Inlined 2Y 1N|IR 213/ 474|CodeSize 1885|Addr 0x7f99242ae690|Src n/a
[engine] opt done SmallInteger>>printString |AST 152|Tier 2|Time 239( 223+16 )ms|Inlined 4Y 1N|IR 313/ 317|CodeSize 1162|Addr 0x7f99242b2210|Src n/a
[engine] opt done ControlFlowGraph>>initialize |AST 38|Tier 2|Time 212( 196+16 )ms|Inlined 8Y 0N|IR 129/ 752|CodeSize 2278|Addr 0x7f99242bf510|Src n/a
[engine] Truffle runtime statistics for engine 3
Compilations : 218
Success : 205
Temporary Bailouts : 5
org.graalvm.compiler.core.common.CancellationBailoutException: Compilation cancelled.: 4
org.graalvm.compiler.core.common.RetryableBailoutException: Assumption invalidated while compiling code: Object[Instance<org.graalvm.compiler.truffle.runtime.OptimizedAssumption>]: 1
Permanent Bailouts : 0
Failed : 0
Interrupted : 8
Invalidated : 6
Latest layout assumption Layout no longer valid: 3
Profiled Argument Types : 2
frame version : 1
Queues : 374
Dequeues : 172
Target inlined into only caller : 169
Split call node : 2
frame version : 1
Splits : 688
Compilation Accuracy : 0.972477
Queue Accuracy : 0.540107
Compilation Utilization : 0.571753
Remaining Compilation Queue : 0
Time to queue : count= 374, sum= 6036320, min= 5, average= 16139.89, max= 284802 (milliseconds), maxTarget=ControlFlowGraph>>initialize
Time waiting in queue : count= 218, sum= 2034348, min= 0, average= 9331.87, max= 45228 (milliseconds), maxTarget=HavlakLoopFinder>>initAllNodes
Time for compilation : count= 210, sum= 163372, min= 26, average= 777.97, max= 5992 (milliseconds), maxTarget=HavlakLoopFinder>>findLoops <split-ddf20fd>
Truffle Tier : count= 205, sum= 81218, min= 24, average= 396.19, max= 2434 (milliseconds), maxTarget=LoopTesterApp>>buildBaseLoop: <split-afb5821>
Graal Tier : count= 205, sum= 52915, min= 1, average= 258.12, max= 3071 (milliseconds), maxTarget=HavlakLoopFinder>>findLoops <split-ddf20fd>
Code Installation : count= 205, sum= 26614, min= 1, average= 129.83, max= 1151 (milliseconds), maxTarget=LoopTesterApp>>constructCFG:p:p: <split-2d195ee4>
Truffle node count : count= 210, sum= 275516, min= 12, average= 1311.98, max= 7479, maxTarget=HavlakLoopFinder>>initAllNodes <split-44abe8b6>
Trivial : count= 210, sum= 105236, min= 4, average= 501.12, max= 2819, maxTarget=LoopTesterApp>>buildDiamond: <split-56ccd751>
Non Trivial : count= 210, sum= 170280, min= 8, average= 810.86, max= 4727, maxTarget=HavlakLoopFinder>>initAllNodes <split-44abe8b6>
Monomorphic : count= 210, sum= 167572, min= 8, average= 797.96, max= 4688, maxTarget=HavlakLoopFinder>>initAllNodes <split-44abe8b6>
Polymorphic : count= 210, sum= 2708, min= 0, average= 12.90, max= 101, maxTarget=LoopTesterApp>>buildDiamond: <split-56ccd751>
Megamorphic : count= 210, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Behavior>>new <split-7c8326a4>
Truffle call count : count= 210, sum= 3129, min= 0, average= 14.90, max= 91, maxTarget=HavlakLoopFinder>>initAllNodes <split-44abe8b6>
Indirect : count= 210, sum= 1, min= 0, average= 0.00, max= 1, maxTarget=Integer>>timesRepeat:
Direct : count= 210, sum= 3128, min= 0, average= 14.90, max= 91, maxTarget=HavlakLoopFinder>>initAllNodes <split-44abe8b6>
Dispatched : count= 210, sum= 86, min= 0, average= 0.41, max= 11, maxTarget=HavlakLoopFinder>>findLoops
Inlined : count= 210, sum= 3042, min= 0, average= 14.49, max= 89, maxTarget=HavlakLoopFinder>>initAllNodes <split-44abe8b6>
---------- :
Cloned : count= 210, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Behavior>>new <split-7c8326a4>
Not Cloned : count= 210, sum= 3131, min= 0, average= 14.91, max= 91, maxTarget=HavlakLoopFinder>>initAllNodes <split-44abe8b6>
Truffle loops : count= 210, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Behavior>>new <split-7c8326a4>
Graal node count :
After Truffle Tier : count= 210, sum= 310005, min= 5, average= 1476.21, max= 12463, maxTarget=HavlakLoopFinder>>findLoops <split-ddf20fd>
After Graal Tier : count= 206, sum= 958509, min= 5, average= 4652.96, max= 44034, maxTarget=HavlakLoopFinder>>findLoops <split-ddf20fd>
Graal compilation result :
Code size : count= 205, sum= 4294670, min= 120, average= 20949.61, max= 187170, maxTarget=HavlakLoopFinder>>findLoops <split-ddf20fd>
Total frame size : count= 205, sum= 69248, min= 32, average= 337.80, max= 1792, maxTarget=LoopTesterApp>>buildDiamond: <split-56ccd751>
Exception handlers : count= 205, sum= 157, min= 0, average= 0.77, max= 15, maxTarget=HavlakLoopFinder>>initAllNodes
Infopoints : count= 205, sum= 38338, min= 4, average= 187.01, max= 1654, maxTarget=HavlakLoopFinder>>findLoops <split-ddf20fd>
CALL : count= 205, sum= 29663, min= 4, average= 144.70, max= 1311, maxTarget=HavlakLoopFinder>>findLoops <split-ddf20fd>
IMPLICIT_EXCEPTION : count= 205, sum= 7851, min= 0, average= 38.30, max= 316, maxTarget=HavlakLoopFinder>>findLoops <split-ddf20fd>
SAFEPOINT : count= 205, sum= 824, min= 0, average= 4.02, max= 27, maxTarget=HavlakLoopFinder>>findLoops <split-ddf20fd>
Marks : count= 205, sum= 2251, min= 5, average= 10.98, max= 45, maxTarget=HavlakLoopFinder>>findLoops <split-ddf20fd>
Data references : count= 205, sum= 31798, min= 0, average= 155.11, max= 1110, maxTarget=HavlakLoopFinder>>findLoops
Raw
b5e925dc-Json.trace.log
# Json (iterations: 250, problem size: 100) on Mon Jul 26 08:21:03 UTC 2021
# `/home/fniephaus/bin/graalvm-ee-java11-21.2.0/bin/trufflesqueak --experimental-options --smalltalk.disable-startup --smalltalk.disable-interrupts --engine.Mode=default --engine.MultiTier=false --engine.DynamicCompilationThresholds=false --engine.TraceCompilation --engine.CompilationStatistics --log.file="/home/fniephaus/dev/are-we-fast-yet/traces/Json.trace.log" --quiet --code "FileStream startUp: true. Harness new run: #(nil 'Json' 250 100)" /home/fniephaus/dev/are-we-fast-yet/benchmarks/Smalltalk/AWFY64-sista-fbc.image`
[engine] opt done SequenceableCollection>>atAllPut: |AST 72|Tier 2|Time 232( 196+36 )ms|Inlined 0Y 0N|IR 184/ 409|CodeSize 1621|Addr 0x7f666b7d5d10|Src n/a
[engine] opt done String class>>new: |AST 51|Tier 2|Time 201( 184+18 )ms|Inlined 2Y 1N|IR 213/ 530|CodeSize 1879|Addr 0x7f666b7e0510|Src n/a
[engine] opt done Character>>asString |AST 22|Tier 2|Time 248( 238+10 )ms|Inlined 4Y 1N|IR 191/ 261|CodeSize 903|Addr 0x7f666b7e6610|Src n/a
[engine] opt done String>>= <split-726a6b94> |AST 69|Tier 2|Time 134( 124+10 )ms|Inlined 3Y 0N|IR 259/ 269|CodeSize 563|Addr 0x7f666b800090|Src n/a
[engine] opt done String>>= <split-1ee29c84> |AST 48|Tier 2|Time 65( 61+3 )ms|Inlined 0Y 0N|IR 43/ 74|CodeSize 358|Addr 0x7f666b808910|Src n/a
[engine] opt done String>>= <split-77128dab> |AST 48|Tier 2|Time 68( 64+4 )ms|Inlined 0Y 0N|IR 43/ 74|CodeSize 358|Addr 0x7f666b80b390|Src n/a
[engine] opt done JsonParser>>read <split-7c8326a4> |AST 105|Tier 2|Time 283( 207+76 )ms|Inlined 6Y 1N|IR 400/ 566|CodeSize 1775|Addr 0x7f666b813310|Src n/a
[engine] opt done String>>= <split-69ce2f62> |AST 69|Tier 2|Time 162( 152+10 )ms|Inlined 3Y 0N|IR 259/ 269|CodeSize 563|Addr 0x7f666b820710|Src n/a
[engine] opt done JsonParser>>readChar: |AST 39|Tier 2|Time 461( 416+44 )ms|Inlined 11Y 1N|IR 710/ 793|CodeSize 2284|Addr 0x7f666b821990|Src n/a
[engine] opt deopt JsonParser>>readChar: |AST 39|Src n/a
[engine] opt done JsonParser>>skipWhiteSpace |AST 19|Tier 2|Time 240( 201+39 )ms|Inlined 8Y 0N|IR 461/ 451|CodeSize 1018|Addr 0x7f666b82b090|Src n/a
[engine] opt done JsonParser>>readStringInternal |AST 111|Tier 2|Time 1094( 925+169 )ms|Inlined 36Y 4N|IR 2129/ 2776|CodeSize 10060|Addr 0x7f666b830610|Src n/a
[engine] opt done String>>= <split-1517f633> |AST 48|Tier 2|Time 61( 58+4 )ms|Inlined 0Y 0N|IR 43/ 74|CodeSize 358|Addr 0x7f666b83a510|Src n/a
[engine] opt done JsonParser>>read <split-5395ea39> |AST 109|Tier 2|Time 245( 229+16 )ms|Inlined 6Y 1N|IR 431/ 681|CodeSize 2118|Addr 0x7f666b83d990|Src n/a
[engine] opt done JsonParser>>readChar: |AST 39|Tier 2|Time 370( 283+87 )ms|Inlined 11Y 1N|IR 731/ 880|CodeSize 2767|Addr 0x7f666b83f810|Src n/a
[engine] opt done Vector>>append: |AST 163|Tier 2|Time 232( 184+48 )ms|Inlined 0Y 0N|IR 453/ 1713|CodeSize 7546|Addr 0x7f666b842410|Src n/a
[engine] opt done String>>= <split-6f6962ba> |AST 69|Tier 2|Time 110( 101+9 )ms|Inlined 3Y 0N|IR 259/ 269|CodeSize 563|Addr 0x7f666b845f90|Src n/a
[engine] opt done String>>compareWith: <split-599f571f> |AST 25|Tier 2|Time 83( 75+7 )ms|Inlined 2Y 0N|IR 205/ 242|CodeSize 577|Addr 0x7f666b848090|Src n/a
[engine] opt done String>>= <split-312afbc7> |AST 69|Tier 2|Time 124( 105+19 )ms|Inlined 3Y 0N|IR 259/ 269|CodeSize 563|Addr 0x7f666b848c90|Src n/a
[engine] opt done String>>= <split-6569dded> |AST 69|Tier 2|Time 120( 111+9 )ms|Inlined 3Y 0N|IR 259/ 269|CodeSize 563|Addr 0x7f666b849a10|Src n/a
[engine] opt done JsonArray>>add: |AST 33|Tier 2|Time 198( 137+61 )ms|Inlined 1Y 0N|IR 524/ 1765|CodeSize 7767|Addr 0x7f666b84b790|Src n/a
[engine] opt done String>>= <split-65327f5> |AST 69|Tier 2|Time 108( 99+9 )ms|Inlined 3Y 0N|IR 259/ 269|CodeSize 563|Addr 0x7f666b854410|Src n/a
[engine] opt done JsonParser>>readArrayElement: |AST 58|Tier 2|Time 728( 383+345 )ms|Inlined 20Y 1N|IR 1469/ 4325|CodeSize 13792|Addr 0x7f666b857690|Src n/a
[engine] opt done JsonParser>>readDigit |AST 34|Tier 2|Time 1040( 729+311 )ms|Inlined 48Y 1N|IR 2977/ 2476|CodeSize 3795|Addr 0x7f666b864690|Src n/a
[engine] opt done JsonParser>>readRequiredChar: |AST 22|Tier 2|Time 307( 258+50 )ms|Inlined 12Y 1N|IR 768/ 975|CodeSize 2576|Addr 0x7f666b86fc10|Src n/a
[engine] opt done JsonParser>>readValue |AST 531|Tier 2|Time 2608(2111+496 )ms|Inlined 74Y 28N|IR 5280/ 7552|CodeSize 24414|Addr 0x7f666b873010|Src n/a
[engine] opt done Vector class>>new |AST 20|Tier 2|Time 90( 85+5 )ms|Inlined 3Y 0N|IR 49/ 292|CodeSize 1023|Addr 0x7f666b886e10|Src n/a
[engine] opt done JsonParser>>readArray |AST 135|Tier 2|Time 2029(1774+255 )ms|Inlined 61Y 6N|IR 2902/ 4678|CodeSize 15776|Addr 0x7f666b887a90|Src n/a
[engine] opt done JsonArray>>initialize |AST 22|Tier 2|Time 100( 93+7 )ms|Inlined 4Y 0N|IR 95/ 435|CodeSize 1288|Addr 0x7f666b892d10|Src n/a
[engine] opt done JsonParser>>startCapture |AST 12|Tier 2|Time 35( 32+3 )ms|Inlined 0Y 0N|IR 74/ 89|CodeSize 254|Addr 0x7f666b893f90|Src n/a
[engine] opt done String>>= <split-72458efc> |AST 69|Tier 2|Time 108( 100+9 )ms|Inlined 3Y 0N|IR 259/ 269|CodeSize 563|Addr 0x7f666b894610|Src n/a
[engine] opt done SequenceableCollection>>copyFrom:to: <split-6a714237> |AST 68|Tier 2|Time 152( 129+23 )ms|Inlined 3Y 1N|IR 326/ 835|CodeSize 3362|Addr 0x7f666b896e10|Src n/a
[engine] opt done JsonParser>>endCapture |AST 64|Tier 2|Time 419( 348+71 )ms|Inlined 8Y 1N|IR 668/ 1169|CodeSize 4019|Addr 0x7f666b898d10|Src n/a
[engine] opt done String>>compareWith: <split-36bc415e> |AST 25|Tier 2|Time 77( 70+7 )ms|Inlined 2Y 0N|IR 205/ 242|CodeSize 577|Addr 0x7f666b89c290|Src n/a
[engine] opt done String>>compareWith:collated: <split-6a74d228> |AST 33|Tier 2|Time 70( 64+7 )ms|Inlined 1Y 0N|IR 171/ 208|CodeSize 560|Addr 0x7f666b89ce90|Src n/a
[engine] opt done Object>>~= |AST 26|Tier 2|Time 117( 107+10 )ms|Inlined 4Y 0N|IR 294/ 301|CodeSize 563|Addr 0x7f666b89dc10|Src n/a
[engine] opt done JsonParser>>readFraction |AST 21|Tier 2|Time 281( 265+16 )ms|Inlined 12Y 1N|IR 768/ 360|CodeSize 707|Addr 0x7f666b8a0610|Src n/a
[engine] opt done JsonNumber class>>new: |AST 40|Tier 2|Time 73( 70+3 )ms|Inlined 2Y 0N|IR 39/ 101|CodeSize 416|Addr 0x7f666b8a2090|Src n/a
[engine] opt done JsonParser>>readExponent |AST 51|Tier 2|Time 472( 441+31 )ms|Inlined 24Y 2N|IR 1377/ 539|CodeSize 918|Addr 0x7f666b8a2690|Src n/a
[engine] opt done HashIndexTable>>initialize |AST 26|Tier 2|Time 231( 108+123 )ms|Inlined 2Y 0N|IR 274/ 536|CodeSize 1560|Addr 0x7f666b8a4190|Src n/a
[engine] opt done HashIndexTable>>hashSlotFor: |AST 52|Tier 2|Time 108( 103+4 )ms|Inlined 1Y 0N|IR 60/ 169|CodeSize 697|Addr 0x7f666b8a6890|Src n/a
[engine] opt done Vector>>size |AST 18|Tier 2|Time 35( 32+3 )ms|Inlined 0Y 0N|IR 32/ 112|CodeSize 434|Addr 0x7f666b8a7090|Src n/a
[engine] opt done JsonParser>>readNumber |AST 136|Tier 2|Time 2195(1994+201 )ms|Inlined 66Y 7N|IR 3751/ 3298|CodeSize 9088|Addr 0x7f666b8a7610|Src n/a
[engine] opt done String>>= <split-56ccd751> |AST 69|Tier 2|Time 108( 99+9 )ms|Inlined 3Y 0N|IR 259/ 269|CodeSize 563|Addr 0x7f666b8b3610|Src n/a
[engine] opt done JsonParser>>readName |AST 35|Tier 2|Time 1029( 702+326 )ms|Inlined 41Y 4N|IR 2411/ 2995|CodeSize 10568|Addr 0x7f666b8b5290|Src n/a
[engine] opt done JsonObject>>add:with: |AST 98|Tier 2|Time 521( 389+132 )ms|Inlined 6Y 0N|IR 1133/ 3573|CodeSize 16688|Addr 0x7f666b8be110|Src n/a
[engine] opt done JsonParser>>readNull |AST 78|Tier 2|Time 1115( 976+139 )ms|Inlined 47Y 4N|IR 2427/ 2413|CodeSize 6741|Addr 0x7f666b8c7490|Src n/a
[engine] opt done JsonParser>>readObjectKeyValuePair: |AST 117|Tier 2|Time 2399(1617+782 )ms|Inlined 93Y 6N|IR 5952/ 8965|CodeSize 35258|Addr 0x7f666b8d5910|Src n/a
[engine] opt done String>>compareWith: <split-f9b5552> |AST 25|Tier 2|Time 81( 74+8 )ms|Inlined 2Y 0N|IR 205/ 242|CodeSize 577|Addr 0x7f666b8eef10|Src n/a
[engine] opt done String>>= <split-755d8470> |AST 69|Tier 2|Time 109( 100+9 )ms|Inlined 3Y 0N|IR 259/ 269|CodeSize 563|Addr 0x7f666b8efb10|Src n/a
[engine] opt done HashIndexTable class>>new |AST 26|Tier 2|Time 234( 168+66 )ms|Inlined 7Y 0N|IR 529/ 1017|CodeSize 2744|Addr 0x7f666b8f1210|Src n/a
[engine] opt done JsonObject>>initialize |AST 54|Tier 2|Time 466( 249+218 )ms|Inlined 16Y 0N|IR 663/ 1788|CodeSize 5537|Addr 0x7f666b8f7610|Src n/a
[engine] opt done String>>= <split-4ac97cbd> |AST 69|Tier 2|Time 242( 233+9 )ms|Inlined 3Y 0N|IR 259/ 269|CodeSize 563|Addr 0x7f666b8fd610|Src n/a
[engine] opt done String>>= <split-2efc3fb3> |AST 69|Tier 2|Time 109( 100+9 )ms|Inlined 3Y 0N|IR 259/ 269|CodeSize 563|Addr 0x7f666b8fe390|Src n/a
[engine] opt done Behavior>>new <split-1ded7b14> |AST 28|Tier 2|Time 397( 255+142 )ms|Inlined 17Y 0N|IR 650/ 1772|CodeSize 5060|Addr 0x7f666b900e10|Src n/a
[engine] opt done JsonParser>>readObject |AST 130|Tier 2|Time 2719(2275+444 )ms|Inlined 78Y 7N|IR 3593/ 5065|CodeSize 15194|Addr 0x7f666b911790|Src n/a
[engine] opt done String>>compareWith:collated: <split-1e63d216> |AST 33|Tier 2|Time 80( 74+7 )ms|Inlined 1Y 0N|IR 171/ 208|CodeSize 560|Addr 0x7f666b91e910|Src n/a
[engine] opt done String>>compareWith: <split-6edaa77a> |AST 25|Tier 2|Time 85( 78+7 )ms|Inlined 2Y 0N|IR 205/ 242|CodeSize 577|Addr 0x7f666b91f390|Src n/a
[engine] opt done String>>= <split-dc0aef5> |AST 69|Tier 2|Time 116( 107+9 )ms|Inlined 3Y 0N|IR 259/ 269|CodeSize 563|Addr 0x7f666b920290|Src n/a
[engine] opt done String>>compareWith: <split-16c3ca31> |AST 25|Tier 2|Time 83( 75+7 )ms|Inlined 2Y 0N|IR 205/ 242|CodeSize 577|Addr 0x7f666b921310|Src n/a
[engine] opt done String>>= <split-4d98f560> |AST 69|Tier 2|Time 118( 109+9 )ms|Inlined 3Y 0N|IR 259/ 269|CodeSize 563|Addr 0x7f666b923890|Src n/a
[engine] opt done String>>compare:with:collated: <split-349d0836> |AST 51|Tier 2|Time 56( 50+6 )ms|Inlined 0Y 0N|IR 137/ 176|CodeSize 560|Addr 0x7f666b924910|Src n/a
[engine] opt done String>>compareWith: <split-21ab988f> |AST 25|Tier 2|Time 85( 77+8 )ms|Inlined 2Y 0N|IR 205/ 242|CodeSize 577|Addr 0x7f666b925210|Src n/a
[engine] opt done String>>= <split-9ddcd12> |AST 69|Tier 2|Time 113( 104+9 )ms|Inlined 3Y 0N|IR 259/ 269|CodeSize 563|Addr 0x7f666b926810|Src n/a
[engine] opt done String>>compareWith:collated: <split-48ea2003> |AST 33|Tier 2|Time 77( 71+7 )ms|Inlined 1Y 0N|IR 171/ 208|CodeSize 560|Addr 0x7f666b928010|Src n/a
[engine] opt done String>>compareWith: <split-35f8a9d3> |AST 25|Tier 2|Time 85( 77+8 )ms|Inlined 2Y 0N|IR 205/ 242|CodeSize 577|Addr 0x7f666b928d90|Src n/a
[engine] opt done String>>= <split-10c9e560> |AST 69|Tier 2|Time 120( 111+9 )ms|Inlined 3Y 0N|IR 259/ 269|CodeSize 563|Addr 0x7f666b92a690|Src n/a
[engine] opt done String>>= <split-13a37e2a> |AST 48|Tier 2|Time 61( 58+3 )ms|Inlined 0Y 0N|IR 43/ 74|CodeSize 358|Addr 0x7f666b92b410|Src n/a
[engine] opt done JsonLiteral class>>TRUE |AST 10|Tier 2|Time 26( 25+2 )ms|Inlined 0Y 0N|IR 21/ 29|CodeSize 207|Addr 0x7f666b92b990|Src n/a
[engine] opt done JsonParser>>read <split-63e5e5b4> |AST 105|Tier 2|Time 214( 201+13 )ms|Inlined 6Y 1N|IR 400/ 566|CodeSize 1775|Addr 0x7f666b92be10|Src n/a
[engine] opt done String>>= <split-ab50b13> |AST 69|Tier 2|Time 112( 103+9 )ms|Inlined 3Y 0N|IR 259/ 269|CodeSize 563|Addr 0x7f666b92cf10|Src n/a
[engine] opt done Vector>>at: |AST 39|Tier 2|Time 59( 55+4 )ms|Inlined 0Y 0N|IR 69/ 103|CodeSize 500|Addr 0x7f666b92e310|Src n/a
[engine] opt done Object>>= |AST 14|Tier 2|Time 29( 25+4 )ms|Inlined 0Y 0N|IR 76/ 135|CodeSize 481|Addr 0x7f666b92e990|Src n/a
[engine] opt done JsonParser>>read <split-4fcc0416> |AST 105|Tier 2|Time 299( 286+13 )ms|Inlined 6Y 1N|IR 400/ 566|CodeSize 1775|Addr 0x7f666b92f490|Src n/a
[engine] opt done JsonLiteral class>>FALSE |AST 10|Tier 2|Time 26( 24+2 )ms|Inlined 0Y 0N|IR 21/ 29|CodeSize 207|Addr 0x7f666b930e90|Src n/a
[engine] opt done JsonParser>>readTrue |AST 78|Tier 2|Time 977( 862+116 )ms|Inlined 47Y 4N|IR 2428/ 2445|CodeSize 6663|Addr 0x7f666b931310|Src n/a
[engine] opt done JsonObject>>at: |AST 60|Tier 2|Time 247( 200+47 )ms|Inlined 10Y 0N|IR 543/ 612|CodeSize 1855|Addr 0x7f666b937b90|Src n/a
[engine] opt done JsonParser>>readFalse |AST 93|Tier 2|Time 1210(1054+157 )ms|Inlined 58Y 5N|IR 3063/ 3046|CodeSize 8112|Addr 0x7f666b93b110|Src n/a
[engine] opt done Json class>>RapBenchmarkMinified |AST 8|Tier 2|Time 24( 23+2 )ms|Inlined 0Y 0N|IR 17/ 20|CodeSize 156|Addr 0x7f666b942590|Src n/a
[engine] opt done JsonParser class>>with: |AST 31|Tier 2|Time 91( 85+7 )ms|Inlined 2Y 0N|IR 186/ 376|CodeSize 1282|Addr 0x7f666b942990|Src n/a
[engine] opt done JsonParser>>parse |AST 82|Tier 2|Time 687( 558+130 )ms|Inlined 23Y 2N|IR 1824/ 3712|CodeSize 11610|Addr 0x7f666b945990|Src n/a
[engine] opt done Json>>benchmark |AST 44|Tier 2|Time 897( 628+269 )ms|Inlined 28Y 2N|IR 1531/ 3197|CodeSize 10474|Addr 0x7f666b94af90|Src n/a
[engine] opt done JsonArray>>size |AST 20|Tier 2|Time 55( 51+4 )ms|Inlined 1Y 0N|IR 46/ 130|CodeSize 519|Addr 0x7f666b950110|Src n/a
[engine] opt done Json>>verifyResult: |AST 126|Tier 2|Time 628( 493+135 )ms|Inlined 35Y 0N|IR 1534/ 1257|CodeSize 3368|Addr 0x7f666b951810|Src n/a
[engine] opt done Benchmark>>innerBenchmarkLoop: |AST 59|Tier 2|Time 1879(1098+781 )ms|Inlined 65Y 2N|IR 3154/ 6144|CodeSize 20024|Addr 0x7f666b956590|Src n/a
[engine] opt done SmallInteger>>printString |AST 152|Tier 2|Time 295( 272+23 )ms|Inlined 4Y 1N|IR 362/ 377|CodeSize 1188|Addr 0x7f666b966c90|Src n/a
[engine] Truffle runtime statistics for engine 3
Compilations : 101
Success : 85
Temporary Bailouts : 3
org.graalvm.compiler.core.common.CancellationBailoutException: Compilation cancelled.: 3
Permanent Bailouts : 0
Failed : 0
Interrupted : 13
Invalidated : 0
Queues : 330
Dequeues : 244
Target inlined into only caller : 241
Split call node : 3
Splits : 493
Compilation Accuracy : 1.000000
Queue Accuracy : 0.260606
Compilation Utilization : 0.530976
Remaining Compilation Queue : 0
Time to queue : count= 330, sum= 880901, min= 121, average= 2669.40, max= 58490 (milliseconds), maxTarget=SmallInteger>>printString
Time waiting in queue : count= 100, sum= 279298, min= 0, average= 2792.99, max= 8253 (milliseconds), maxTarget=String>>compare:with:collated: <split-349d0836>
Time for compilation : count= 88, sum= 35625, min= 25, average= 404.84, max= 2719 (milliseconds), maxTarget=JsonParser>>readObject
Truffle Tier : count= 85, sum= 27714, min= 22, average= 326.05, max= 2274 (milliseconds), maxTarget=JsonParser>>readObject
Graal Tier : count= 85, sum= 4750, min= 1, average= 55.89, max= 592 (milliseconds), maxTarget=JsonParser>>readObjectKeyValuePair:
Code Installation : count= 85, sum= 2108, min= 1, average= 24.80, max= 202 (milliseconds), maxTarget=JsonParser>>readValue
Truffle node count : count= 88, sum= 92489, min= 12, average= 1051.01, max= 7931, maxTarget=JsonParser>>readObjectKeyValuePair:
Trivial : count= 88, sum= 34364, min= 4, average= 390.50, max= 2969, maxTarget=JsonParser>>readObjectKeyValuePair:
Non Trivial : count= 88, sum= 58125, min= 8, average= 660.51, max= 4962, maxTarget=JsonParser>>readObjectKeyValuePair:
Monomorphic : count= 88, sum= 57531, min= 8, average= 653.76, max= 4919, maxTarget=JsonParser>>readObjectKeyValuePair:
Polymorphic : count= 88, sum= 594, min= 0, average= 6.75, max= 43, maxTarget=JsonParser>>readObjectKeyValuePair:
Megamorphic : count= 88, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=SequenceableCollection>>atAllPut:
Truffle call count : count= 88, sum= 1227, min= 0, average= 13.94, max= 102, maxTarget=JsonParser>>readValue
Indirect : count= 88, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=SequenceableCollection>>atAllPut:
Direct : count= 88, sum= 1227, min= 0, average= 13.94, max= 102, maxTarget=JsonParser>>readValue
Dispatched : count= 88, sum= 99, min= 0, average= 1.13, max= 28, maxTarget=JsonParser>>readValue
Inlined : count= 88, sum= 1128, min= 0, average= 12.82, max= 93, maxTarget=JsonParser>>readObjectKeyValuePair:
---------- :
Cloned : count= 88, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=SequenceableCollection>>atAllPut:
Not Cloned : count= 88, sum= 1231, min= 0, average= 13.99, max= 102, maxTarget=JsonParser>>readValue
Truffle loops : count= 88, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=SequenceableCollection>>atAllPut:
Graal node count :
After Truffle Tier : count= 88, sum= 68906, min= 17, average= 783.02, max= 5952, maxTarget=JsonParser>>readObjectKeyValuePair:
After Graal Tier : count= 85, sum= 96493, min= 20, average= 1135.21, max= 8965, maxTarget=JsonParser>>readObjectKeyValuePair:
Graal compilation result :
Code size : count= 85, sum= 309125, min= 156, average= 3636.76, max= 35258, maxTarget=JsonParser>>readObjectKeyValuePair:
Total frame size : count= 85, sum= 7392, min= 32, average= 86.96, max= 576, maxTarget=JsonParser>>readObjectKeyValuePair:
Exception handlers : count= 85, sum= 38, min= 0, average= 0.45, max= 24, maxTarget=JsonParser>>readValue
Infopoints : count= 85, sum= 3384, min= 4, average= 39.81, max= 303, maxTarget=JsonParser>>readValue
CALL : count= 85, sum= 2574, min= 4, average= 30.28, max= 228, maxTarget=JsonParser>>readValue
IMPLICIT_EXCEPTION : count= 85, sum= 776, min= 0, average= 9.13, max= 74, maxTarget=JsonParser>>readValue
SAFEPOINT : count= 85, sum= 34, min= 0, average= 0.40, max= 4, maxTarget=JsonObject>>add:with:
Marks : count= 85, sum= 591, min= 6, average= 6.95, max= 31, maxTarget=JsonParser>>readValue
Data references : count= 85, sum= 3850, min= 2, average= 45.29, max= 321, maxTarget=JsonParser>>readValue
Raw
b5e925dc-List.trace.log
# List (iterations: 250, problem size: 1500) on Mon Jul 26 08:05:06 UTC 2021
# `/home/fniephaus/bin/graalvm-ee-java11-21.2.0/bin/trufflesqueak --experimental-options --smalltalk.disable-startup --smalltalk.disable-interrupts --engine.Mode=default --engine.MultiTier=false --engine.DynamicCompilationThresholds=false --engine.TraceCompilation --engine.CompilationStatistics --log.file="/home/fniephaus/dev/are-we-fast-yet/traces/List.trace.log" --quiet --code "FileStream startUp: true. Harness new run: #(nil 'List' 250 1500)" /home/fniephaus/dev/are-we-fast-yet/benchmarks/Smalltalk/AWFY64-sista-fbc.image`
[engine] opt done List>>isShorter:than: <split-4d847d32> |AST 75|Tier 2|Time 117( 72+44 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f7ca010|Src n/a
[engine] opt done List>>isShorter:than: <split-644abb8f> |AST 75|Tier 2|Time 173( 128+46 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f7cba90|Src n/a
[engine] opt done List>>isShorter:than: <split-70325d20> |AST 75|Tier 2|Time 117( 75+42 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f7cab90|Src n/a
[engine] opt done List>>isShorter:than: <split-3d7fa3ae> |AST 75|Tier 2|Time 93( 82+11 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f7de710|Src n/a
[engine] opt done List>>isShorter:than: <split-5f462e3b> |AST 75|Tier 2|Time 82( 69+12 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f7df590|Src n/a
[engine] opt done List>>isShorter:than: <split-58065f0c> |AST 75|Tier 2|Time 69( 58+11 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f7e9710|Src n/a
[engine] opt done List>>isShorter:than: <split-585c13de> |AST 75|Tier 2|Time 73( 61+12 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f7eaa10|Src n/a
[engine] opt done List>>isShorter:than: <split-187eb9a8> |AST 75|Tier 2|Time 67( 56+10 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f7eec10|Src n/a
[engine] opt done List>>isShorter:than: <split-726a6b94> |AST 75|Tier 2|Time 67( 55+12 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f7ef790|Src n/a
[engine] opt done List>>isShorter:than: <split-6ad11a56> |AST 75|Tier 2|Time 77( 58+19 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f7f0c10|Src n/a
[engine] opt done List>>isShorter:than: <split-522b2631> |AST 75|Tier 2|Time 74( 55+20 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f7f1790|Src n/a
[engine] opt done List>>isShorter:than: <split-7c8326a4> |AST 72|Tier 2|Time 65( 57+9 )ms|Inlined 0Y 0N|IR 135/ 204|CodeSize 644|Addr 0x7fe20f7f7e10|Src n/a
[engine] opt done List>>isShorter:than: <split-77128dab> |AST 75|Tier 2|Time 71( 56+16 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f7f8790|Src n/a
[engine] opt done List>>isShorter:than: <split-3ff57625> |AST 75|Tier 2|Time 66( 56+11 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f7fbd90|Src n/a
[engine] opt done List>>isShorter:than: <split-63429932> |AST 72|Tier 2|Time 66( 53+12 )ms|Inlined 0Y 0N|IR 135/ 204|CodeSize 644|Addr 0x7fe20f7fc910|Src n/a
[engine] opt done List>>isShorter:than: <split-1ee29c84> |AST 75|Tier 2|Time 65( 54+11 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f7ff110|Src n/a
[engine] opt done List>>isShorter:than: <split-69ce2f62> |AST 72|Tier 2|Time 62( 52+10 )ms|Inlined 0Y 0N|IR 135/ 204|CodeSize 644|Addr 0x7fe20f800110|Src n/a
[engine] opt done List>>isShorter:than: <split-c9d82f9> |AST 75|Tier 2|Time 64( 54+10 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f800d10|Src n/a
[engine] opt done List>>isShorter:than: <split-6f012914> |AST 75|Tier 2|Time 64( 53+10 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f801810|Src n/a
[engine] opt done List>>isShorter:than: <split-18fdb6cf> |AST 75|Tier 2|Time 120( 110+10 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f808690|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-61533ae> |AST 135|Tier 2|Time 556( 389+168 )ms|Inlined 15Y 5N|IR 1640/ 2271|CodeSize 7603|Addr 0x7fe20f810010|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-d02f8d> |AST 135|Tier 2|Time 1139( 909+231 )ms|Inlined 17Y 8N|IR 1996/ 3087|CodeSize 11085|Addr 0x7fe20f822e90|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: |AST 135|Tier 2|Time 1973(1140+832 )ms|Inlined 33Y 32N|IR 4815/ 7927|CodeSize 31986|Addr 0x7fe20f832b90|Src n/a
[engine] opt done List>>isShorter:than: <split-732bb66d> |AST 72|Tier 2|Time 58( 49+9 )ms|Inlined 0Y 0N|IR 138/ 214|CodeSize 726|Addr 0x7fe20f856510|Src n/a
[engine] opt done List>>isShorter:than: <split-720653c2> |AST 75|Tier 2|Time 61( 51+10 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f857b10|Src n/a
[engine] opt done List>>isShorter:than: <split-43b0ade> |AST 75|Tier 2|Time 60( 50+10 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f858990|Src n/a
[engine] opt done List>>isShorter:than: <split-4fe01803> |AST 72|Tier 2|Time 63( 54+9 )ms|Inlined 0Y 0N|IR 138/ 214|CodeSize 726|Addr 0x7fe20f859d10|Src n/a
[engine] opt done List>>isShorter:than: <split-4463d9d3> |AST 75|Tier 2|Time 64( 54+10 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f85a790|Src n/a
[engine] opt done List>>isShorter:than: <split-5395ea39> |AST 75|Tier 2|Time 63( 53+10 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f85bf10|Src n/a
[engine] opt done List>>isShorter:than: <split-1517f633> |AST 75|Tier 2|Time 64( 54+10 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f85d110|Src n/a
[engine] opt done List>>isShorter:than: <split-4565a70a> |AST 75|Tier 2|Time 63( 53+10 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f85dc90|Src n/a
[engine] opt done List>>isShorter:than: <split-6ad5923a> |AST 75|Tier 2|Time 64( 54+10 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f85e810|Src n/a
[engine] opt done List>>isShorter:than: <split-312afbc7> |AST 75|Tier 2|Time 198( 188+10 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f860e10|Src n/a
[engine] opt done List>>isShorter:than: <split-599f571f> |AST 75|Tier 2|Time 199( 189+10 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f861990|Src n/a
[engine] opt done List>>isShorter:than: <split-6569dded> |AST 75|Tier 2|Time 63( 53+10 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f863290|Src n/a
[engine] opt done List>>isShorter:than: <split-7b60c3e> |AST 72|Tier 2|Time 60( 52+9 )ms|Inlined 0Y 0N|IR 138/ 214|CodeSize 726|Addr 0x7fe20f863e10|Src n/a
[engine] opt done List>>isShorter:than: <split-3fbfa96> |AST 72|Tier 2|Time 59( 51+8 )ms|Inlined 0Y 0N|IR 135/ 204|CodeSize 644|Addr 0x7fe20f864b10|Src n/a
[engine] opt done List>>isShorter:than: <split-45f24169> |AST 75|Tier 2|Time 64( 54+10 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f865490|Src n/a
[engine] opt done List>>isShorter:than: <split-710d7aff> |AST 72|Tier 2|Time 59( 51+8 )ms|Inlined 0Y 0N|IR 135/ 204|CodeSize 644|Addr 0x7fe20f866310|Src n/a
[engine] opt done List>>isShorter:than: <split-2d7e1102> |AST 72|Tier 2|Time 59( 51+8 )ms|Inlined 0Y 0N|IR 135/ 204|CodeSize 644|Addr 0x7fe20f867190|Src n/a
[engine] opt done List>>isShorter:than: <split-301d8120> |AST 72|Tier 2|Time 59( 51+8 )ms|Inlined 0Y 0N|IR 135/ 204|CodeSize 644|Addr 0x7fe20f867e10|Src n/a
[engine] opt done List>>isShorter:than: <split-13d186db> |AST 72|Tier 2|Time 60( 52+8 )ms|Inlined 0Y 0N|IR 135/ 204|CodeSize 644|Addr 0x7fe20f868790|Src n/a
[engine] opt done List>>isShorter:than: <split-65327f5> |AST 72|Tier 2|Time 58( 50+8 )ms|Inlined 0Y 0N|IR 135/ 204|CodeSize 644|Addr 0x7fe20f869110|Src n/a
[engine] opt done List>>isShorter:than: <split-2adddc06> |AST 72|Tier 2|Time 60( 52+8 )ms|Inlined 0Y 0N|IR 135/ 204|CodeSize 644|Addr 0x7fe20f86ac90|Src n/a
[engine] opt done List>>isShorter:than: <split-a0a9fa5> |AST 72|Tier 2|Time 59( 51+8 )ms|Inlined 0Y 0N|IR 135/ 204|CodeSize 644|Addr 0x7fe20f86b890|Src n/a
[engine] opt done List>>isShorter:than: <split-36bc415e> |AST 75|Tier 2|Time 64( 54+10 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f86c210|Src n/a
[engine] opt done List>>isShorter:than: <split-349d0836> |AST 75|Tier 2|Time 69( 59+10 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f86df90|Src n/a
[engine] opt done List>>isShorter:than: <split-6a74d228> |AST 75|Tier 2|Time 63( 53+10 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f86ed90|Src n/a
[engine] opt done List>>isShorter:than: <split-6a714237> |AST 72|Tier 2|Time 60( 52+8 )ms|Inlined 0Y 0N|IR 135/ 204|CodeSize 644|Addr 0x7fe20f870590|Src n/a
[engine] opt done List>>isShorter:than: <split-3e134896> |AST 72|Tier 2|Time 71( 51+20 )ms|Inlined 0Y 0N|IR 138/ 214|CodeSize 726|Addr 0x7fe20f873590|Src n/a
[engine] opt done List>>isShorter:than: <split-72ba28ee> |AST 75|Tier 2|Time 79( 69+11 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f874010|Src n/a
[engine] opt done List>>isShorter:than: <split-2e3a5237> |AST 75|Tier 2|Time 63( 53+10 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f875510|Src n/a
[engine] opt done List>>isShorter:than: <split-4ebadd3d> |AST 72|Tier 2|Time 64( 56+8 )ms|Inlined 0Y 0N|IR 135/ 204|CodeSize 644|Addr 0x7fe20f876490|Src n/a
[engine] opt done List>>isShorter:than: <split-6ac97b84> |AST 72|Tier 2|Time 59( 51+8 )ms|Inlined 0Y 0N|IR 135/ 204|CodeSize 644|Addr 0x7fe20f876e10|Src n/a
[engine] opt done List>>isShorter:than: <split-4917d36b> |AST 75|Tier 2|Time 69( 58+11 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f877790|Src n/a
[engine] opt done List>>isShorter:than: <split-35c09b94> |AST 72|Tier 2|Time 59( 51+8 )ms|Inlined 0Y 0N|IR 135/ 204|CodeSize 644|Addr 0x7fe20f878310|Src n/a
[engine] opt done List>>isShorter:than: <split-2d0bfb24> |AST 72|Tier 2|Time 151( 143+8 )ms|Inlined 0Y 0N|IR 135/ 204|CodeSize 644|Addr 0x7fe20f879790|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-1a2ebce4> |AST 135|Tier 2|Time 2387(1254+1133)ms|Inlined 31Y 49N|IR 5353/ 9866|CodeSize 42444|Addr 0x7fe20f87b410|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-7b44b63d> |AST 135|Tier 2|Time 2869(1473+1396)ms|Inlined 41Y 64N|IR 7013/12534|CodeSize 54166|Addr 0x7fe20f8a8a10|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-4a699efa> |AST 135|Tier 2|Time 2804(1401+1403)ms|Inlined 41Y 64N|IR 7013/12534|CodeSize 54166|Addr 0x7fe20f8e1290|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-60baef24> |AST 135|Tier 2|Time 1267( 876+391 )ms|Inlined 27Y 15N|IR 3254/ 4817|CodeSize 18124|Addr 0x7fe20f919590|Src n/a
[engine] opt done List>>makeList: |AST 76|Tier 2|Time 457( 407+51 )ms|Inlined 14Y 1N|IR 378/ 1092|CodeSize 4108|Addr 0x7fe20f935810|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-599d3e84> |AST 135|Tier 2|Time 1980(1171+809 )ms|Inlined 31Y 29N|IR 4442/ 7380|CodeSize 29459|Addr 0x7fe20f939690|Src n/a
[engine] opt done List>>isShorter:than: <split-66434cc8> |AST 72|Tier 2|Time 60( 51+9 )ms|Inlined 0Y 0N|IR 138/ 214|CodeSize 726|Addr 0x7fe20f959090|Src n/a
[engine] opt done List>>isShorter:than: <split-42f22995> |AST 75|Tier 2|Time 62( 52+10 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f95ff90|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-c3fa05a> |AST 135|Tier 2|Time 2956(1347+1609)ms|Inlined 41Y 64N|IR 7013/12659|CodeSize 54734|Addr 0x7fe20f964690|Src n/a
[engine] opt done List>>isShorter:than: <split-693e4d19> |AST 75|Tier 2|Time 61( 51+10 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20f99cc10|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-38499e48> |AST 135|Tier 2|Time 2755(1342+1413)ms|Inlined 41Y 64N|IR 7013/12534|CodeSize 54166|Addr 0x7fe20f9a7b90|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-777e971c> |AST 135|Tier 2|Time 1686(1065+621 )ms|Inlined 23Y 33N|IR 3808/ 6976|CodeSize 29576|Addr 0x7fe20f9e0190|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-21fdaffe> |AST 130|Tier 2|Time 1828(1024+804 )ms|Inlined 23Y 33N|IR 3739/ 6932|CodeSize 29686|Addr 0x7fe20f9fe710|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-6525a9ad> |AST 135|Tier 2|Time 2695(1282+1413)ms|Inlined 41Y 64N|IR 7013/12626|CodeSize 54664|Addr 0x7fe20fa1ca90|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-286b39c2> |AST 135|Tier 2|Time 2865(1462+1403)ms|Inlined 41Y 64N|IR 7013/12659|CodeSize 54734|Addr 0x7fe20fa55490|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-5cbb84b1> |AST 135|Tier 2|Time 2728(1323+1406)ms|Inlined 41Y 64N|IR 7013/12626|CodeSize 54728|Addr 0x7fe20fa91010|Src n/a
[engine] opt done List>>isShorter:than: <split-5328a9c1> |AST 72|Tier 2|Time 57( 49+8 )ms|Inlined 0Y 0N|IR 135/ 204|CodeSize 644|Addr 0x7fe20fac9c90|Src n/a
[engine] opt done List>>isShorter:than: <split-6f6962ba> |AST 72|Tier 2|Time 56( 48+8 )ms|Inlined 0Y 0N|IR 135/ 204|CodeSize 644|Addr 0x7fe20facb210|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-27068a50> |AST 130|Tier 2|Time 2753(1336+1417)ms|Inlined 41Y 64N|IR 6948/12621|CodeSize 54696|Addr 0x7fe20facbb90|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-682c1e93> |AST 28|Tier 2|Time 74( 63+11 )ms|Inlined 1Y 0N|IR 172/ 259|CodeSize 677|Addr 0x7fe20fb04410|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-7ade3ea3> |AST 135|Tier 2|Time 637( 578+59 )ms|Inlined 7Y 1N|IR 738/ 1217|CodeSize 3495|Addr 0x7fe20fb05090|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-6d467c87> |AST 28|Tier 2|Time 72( 61+11 )ms|Inlined 1Y 0N|IR 172/ 259|CodeSize 677|Addr 0x7fe20fb08810|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-68e62ca4> |AST 135|Tier 2|Time 2718(1328+1390)ms|Inlined 41Y 64N|IR 7013/12626|CodeSize 54728|Addr 0x7fe20fb09490|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-29182679> |AST 28|Tier 2|Time 74( 63+11 )ms|Inlined 1Y 0N|IR 172/ 259|CodeSize 677|Addr 0x7fe20fb41c10|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-511d5d04> |AST 135|Tier 2|Time 2700(1319+1381)ms|Inlined 41Y 64N|IR 7013/12534|CodeSize 54166|Addr 0x7fe20fb43210|Src n/a
[engine] opt done List>>isShorter:than: <split-15723761> |AST 75|Tier 2|Time 226( 216+10 )ms|Inlined 0Y 0N|IR 200/ 238|CodeSize 801|Addr 0x7fe20fb7b510|Src n/a
[engine] opt done List>>isShorter:than: <split-466d49f0> |AST 72|Tier 2|Time 56( 48+8 )ms|Inlined 0Y 0N|IR 135/ 204|CodeSize 644|Addr 0x7fe20fb7c090|Src n/a
[engine] opt done List>>isShorter:than: <split-1b9c1b51> |AST 72|Tier 2|Time 56( 48+9 )ms|Inlined 0Y 0N|IR 138/ 214|CodeSize 726|Addr 0x7fe20fb7ca10|Src n/a
[engine] opt done List>>isShorter:than: <split-2e52fb3e> |AST 72|Tier 2|Time 56( 48+8 )ms|Inlined 0Y 0N|IR 138/ 214|CodeSize 726|Addr 0x7fe20fb7d490|Src n/a
[engine] opt done List>>isShorter:than: <split-16fe9c29> |AST 72|Tier 2|Time 57( 48+9 )ms|Inlined 0Y 0N|IR 138/ 214|CodeSize 726|Addr 0x7fe20fb7eb10|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-48bfb884> |AST 28|Tier 2|Time 71( 60+11 )ms|Inlined 1Y 0N|IR 172/ 259|CodeSize 677|Addr 0x7fe20fb7f590|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-11653e3b> |AST 28|Tier 2|Time 71( 60+11 )ms|Inlined 1Y 0N|IR 172/ 259|CodeSize 677|Addr 0x7fe20fb80210|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-715fb77> |AST 28|Tier 2|Time 71( 60+11 )ms|Inlined 1Y 0N|IR 172/ 259|CodeSize 677|Addr 0x7fe20fb80e90|Src n/a
[engine] opt done List>>isShorter:than: <split-3e9803c2> |AST 72|Tier 2|Time 56( 48+8 )ms|Inlined 0Y 0N|IR 138/ 214|CodeSize 726|Addr 0x7fe20fb81b10|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-57bd802b> |AST 135|Tier 2|Time 2693(1302+1392)ms|Inlined 41Y 64N|IR 7013/12534|CodeSize 54166|Addr 0x7fe20fb82590|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-5b78fdb1> |AST 130|Tier 2|Time 2693(1302+1391)ms|Inlined 41Y 64N|IR 6948/12621|CodeSize 54696|Addr 0x7fe20fbbad90|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-72458efc> |AST 135|Tier 2|Time 630( 577+53 )ms|Inlined 7Y 1N|IR 738/ 1041|CodeSize 2807|Addr 0x7fe20fbf3a90|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-6d367020> |AST 135|Tier 2|Time 1670(1056+615 )ms|Inlined 23Y 33N|IR 3808/ 6870|CodeSize 29114|Addr 0x7fe20fbf6e90|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-4903a5f2> |AST 135|Tier 2|Time 1359(1026+333 )ms|Inlined 13Y 18N|IR 2196/ 3872|CodeSize 16372|Addr 0x7fe20fc15090|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-38e4c717> |AST 28|Tier 2|Time 75( 63+11 )ms|Inlined 1Y 0N|IR 172/ 259|CodeSize 677|Addr 0x7fe20fc25e90|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-3587d21c> |AST 135|Tier 2|Time 2695(1275+1420)ms|Inlined 41Y 64N|IR 7013/12659|CodeSize 54734|Addr 0x7fe20fc28c90|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-cf0be51> |AST 135|Tier 2|Time 2682(1460+1222)ms|Inlined 41Y 64N|IR 7013/12534|CodeSize 54166|Addr 0x7fe20fc61210|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-5d7a829b> |AST 28|Tier 2|Time 180( 168+12 )ms|Inlined 1Y 0N|IR 172/ 259|CodeSize 677|Addr 0x7fe20fcd1810|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-21ecb185> |AST 135|Tier 2|Time 2781(1455+1326)ms|Inlined 41Y 64N|IR 7013/12534|CodeSize 54166|Addr 0x7fe20fc99510|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-14305f76> |AST 28|Tier 2|Time 74( 63+11 )ms|Inlined 1Y 0N|IR 172/ 259|CodeSize 677|Addr 0x7fe20fcd2e10|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-331e3a47> |AST 28|Tier 2|Time 74( 63+11 )ms|Inlined 1Y 0N|IR 172/ 259|CodeSize 677|Addr 0x7fe20fcd3a90|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-7b8a2414> |AST 28|Tier 2|Time 73( 62+11 )ms|Inlined 1Y 0N|IR 172/ 259|CodeSize 677|Addr 0x7fe20fcd4b90|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-705d6e1c> |AST 135|Tier 2|Time 1075( 635+440 )ms|Inlined 15Y 17N|IR 2263/ 3994|CodeSize 16074|Addr 0x7fe20fcd5810|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-6bb910d5> |AST 135|Tier 2|Time 238( 186+52 )ms|Inlined 9Y 0N|IR 841/ 901|CodeSize 2187|Addr 0x7fe20fce5d10|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-62466f2d> |AST 135|Tier 2|Time 1358(1033+325 )ms|Inlined 13Y 18N|IR 2196/ 3872|CodeSize 16372|Addr 0x7fe20fce8c90|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-2b3bd592> |AST 135|Tier 2|Time 2685(1314+1371)ms|Inlined 41Y 64N|IR 7013/12626|CodeSize 54728|Addr 0x7fe20fcf9a10|Src n/a
[engine] opt done List>>isShorter:than: <split-4525d1d3> |AST 72|Tier 2|Time 57( 49+9 )ms|Inlined 0Y 0N|IR 138/ 214|CodeSize 726|Addr 0x7fe20fd32190|Src n/a
[engine] opt done List>>isShorter:than: <split-7c974942> |AST 72|Tier 2|Time 56( 48+8 )ms|Inlined 0Y 0N|IR 135/ 204|CodeSize 644|Addr 0x7fe20fd32c10|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-7ba8f10a> |AST 130|Tier 2|Time 2673(1302+1372)ms|Inlined 41Y 64N|IR 6948/12621|CodeSize 54696|Addr 0x7fe20fd33590|Src n/a
[engine] opt done List>>isShorter:than: <split-1eea9d2d> |AST 72|Tier 2|Time 55( 48+8 )ms|Inlined 0Y 0N|IR 135/ 204|CodeSize 644|Addr 0x7fe20fd6be10|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-1f9d085e> |AST 28|Tier 2|Time 74( 63+11 )ms|Inlined 1Y 0N|IR 172/ 259|CodeSize 677|Addr 0x7fe20fd6c790|Src n/a
[engine] opt done List>>isShorter:than: <split-bd2f5a9> |AST 72|Tier 2|Time 57( 49+8 )ms|Inlined 0Y 0N|IR 138/ 214|CodeSize 726|Addr 0x7fe20fd6d410|Src n/a
[engine] opt done List>>isShorter:than: <split-60921b21> |AST 72|Tier 2|Time 58( 49+8 )ms|Inlined 0Y 0N|IR 138/ 214|CodeSize 726|Addr 0x7fe20fd6de90|Src n/a
[engine] opt done List>>isShorter:than: <split-4d4960c8> |AST 72|Tier 2|Time 226( 217+9 )ms|Inlined 0Y 0N|IR 138/ 214|CodeSize 726|Addr 0x7fe20fd6e910|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-5f168c0c> |AST 135|Tier 2|Time 2673(1278+1396)ms|Inlined 41Y 64N|IR 7013/12626|CodeSize 54728|Addr 0x7fe20fd6fa10|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-3946758f> |AST 28|Tier 2|Time 72( 61+11 )ms|Inlined 1Y 0N|IR 172/ 259|CodeSize 677|Addr 0x7fe20fda8190|Src n/a
[engine] opt done List>>isShorter:than: <split-7808fb9> |AST 72|Tier 2|Time 57( 49+8 )ms|Inlined 0Y 0N|IR 135/ 204|CodeSize 644|Addr 0x7fe20fda8e10|Src n/a
[engine] opt done List>>isShorter:than: <split-773bd77b> |AST 72|Tier 2|Time 57( 49+8 )ms|Inlined 0Y 0N|IR 135/ 204|CodeSize 644|Addr 0x7fe20fda9790|Src n/a
[engine] opt done List>>isShorter:than: <split-6b580b88> |AST 72|Tier 2|Time 57( 49+8 )ms|Inlined 0Y 0N|IR 135/ 204|CodeSize 644|Addr 0x7fe20fdaa110|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-23f48b4b> |AST 28|Tier 2|Time 71( 60+11 )ms|Inlined 1Y 0N|IR 172/ 259|CodeSize 677|Addr 0x7fe20fdaaa90|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-3984fbef> |AST 28|Tier 2|Time 175( 164+11 )ms|Inlined 1Y 0N|IR 172/ 259|CodeSize 677|Addr 0x7fe20fdac010|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-2f9cb143> |AST 28|Tier 2|Time 71( 60+11 )ms|Inlined 1Y 0N|IR 172/ 259|CodeSize 677|Addr 0x7fe20fdacc90|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-a8827f7> |AST 135|Tier 2|Time 1307( 877+429 )ms|Inlined 13Y 18N|IR 2196/ 3872|CodeSize 16372|Addr 0x7fe20fdadf10|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-1fca4e11> |AST 135|Tier 2|Time 2820(1325+1495)ms|Inlined 41Y 64N|IR 7013/12534|CodeSize 54166|Addr 0x7fe20fdbec10|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-67a3b198> |AST 130|Tier 2|Time 2708(1313+1395)ms|Inlined 41Y 64N|IR 6948/12654|CodeSize 54718|Addr 0x7fe20fdf9310|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-4df90562> |AST 28|Tier 2|Time 71( 60+11 )ms|Inlined 1Y 0N|IR 172/ 259|CodeSize 677|Addr 0x7fe20fe32390|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-4ace7480> |AST 135|Tier 2|Time 2711(1315+1395)ms|Inlined 41Y 64N|IR 7013/12626|CodeSize 54728|Addr 0x7fe20fe36890|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-6a84bc2a> |AST 28|Tier 2|Time 73( 62+11 )ms|Inlined 1Y 0N|IR 172/ 259|CodeSize 677|Addr 0x7fe20fe6ff90|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-152139c> |AST 135|Tier 2|Time 2669(1298+1371)ms|Inlined 41Y 64N|IR 7013/12534|CodeSize 54166|Addr 0x7fe20fe70c10|Src n/a
[engine] opt done ListElement>>length |AST 43|Tier 2|Time 94( 76+18 )ms|Inlined 2Y 1N|IR 321/ 521|CodeSize 1765|Addr 0x7fe20fea8f10|Src n/a
[engine] opt done List>>verifyResult: |AST 15|Tier 2|Time 30( 28+2 )ms|Inlined 0Y 0N|IR 25/ 28|CodeSize 189|Addr 0x7fe20feaa790|Src n/a
[engine] opt done List>>benchmark |AST 76|Tier 2|Time 1278(1120+158 )ms|Inlined 48Y 5N|IR 1110/ 2636|CodeSize 9009|Addr 0x7fe20feac890|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-2c779e5> |AST 135|Tier 2|Time 2675(1304+1371)ms|Inlined 41Y 64N|IR 7013/12534|CodeSize 54166|Addr 0x7fe20feb6a90|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-5183d589> |AST 135|Tier 2|Time 2679(1308+1371)ms|Inlined 41Y 64N|IR 7013/12534|CodeSize 54166|Addr 0x7fe20feeed90|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-1a5b8489> |AST 130|Tier 2|Time 738( 535+203 )ms|Inlined 5Y 2N|IR 554/ 1083|CodeSize 3125|Addr 0x7fe20ff28a90|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-34b9fc7d> |AST 28|Tier 2|Time 72( 61+11 )ms|Inlined 1Y 0N|IR 172/ 259|CodeSize 677|Addr 0x7fe20ff2bc10|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-6f8f8a80> |AST 130|Tier 2|Time 1042( 999+43 )ms|Inlined 3Y 3N|IR 440/ 1014|CodeSize 3242|Addr 0x7fe20ff2d110|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-72805168> |AST 130|Tier 2|Time 2902(1324+1578)ms|Inlined 41Y 64N|IR 6948/12654|CodeSize 54718|Addr 0x7fe20ff2fd90|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-4b1c0397> |AST 130|Tier 2|Time 2745(1345+1400)ms|Inlined 41Y 64N|IR 6948/12621|CodeSize 54696|Addr 0x7fe20ff68410|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-cda0432> |AST 130|Tier 2|Time 663( 612+52 )ms|Inlined 5Y 2N|IR 554/ 1077|CodeSize 3182|Addr 0x7fe20ffa0c90|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-27f1bbe0> |AST 130|Tier 2|Time 2731(1329+1402)ms|Inlined 41Y 64N|IR 6948/12621|CodeSize 54696|Addr 0x7fe20ffa3d90|Src n/a
[engine] opt done ListElement>>length <split-4482469c> |AST 38|Tier 2|Time 211( 71+141 )ms|Inlined 2Y 1N|IR 267/ 504|CodeSize 1619|Addr 0x7fe20ffdc610|Src n/a
[engine] opt done ListElement>>length <split-47c64cfe> |AST 43|Tier 2|Time 92( 72+20 )ms|Inlined 2Y 1N|IR 321/ 595|CodeSize 2118|Addr 0x7fe20ffdda10|Src n/a
[engine] opt done Benchmark>>innerBenchmarkLoop: |AST 59|Tier 2|Time 536( 524+13 )ms|Inlined 1Y 1N|IR 136/ 445|CodeSize 1719|Addr 0x7fe20ffdf590|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-7c211fd0> |AST 130|Tier 2|Time 2617(1274+1343)ms|Inlined 41Y 64N|IR 6948/12654|CodeSize 54718|Addr 0x7fe20ffe0790|Src n/a
[engine] opt done List>>talkWithX:withY:withZ: <split-2d84cb86> |AST 130|Tier 2|Time 2614(1272+1342)ms|Inlined 41Y 64N|IR 6948/12621|CodeSize 54696|Addr 0x7fe210018e10|Src n/a
[engine] opt done String class>>new: |AST 51|Tier 2|Time 177( 159+19 )ms|Inlined 2Y 1N|IR 213/ 474|CodeSize 1885|Addr 0x7fe210054a90|Src n/a
[engine] opt done SmallInteger>>printString |AST 152|Tier 2|Time 246( 227+19 )ms|Inlined 4Y 1N|IR 362/ 377|CodeSize 1188|Addr 0x7fe210058a90|Src n/a
[engine] Truffle runtime statistics for engine 3
Compilations : 154
Success : 150
Temporary Bailouts : 0
Permanent Bailouts : 0
Failed : 0
Interrupted : 4
Invalidated : 0
Queues : 229
Dequeues : 78
Target inlined into only caller : 78
Splits : 387
Compilation Accuracy : 1.000000
Queue Accuracy : 0.659389
Compilation Utilization : 0.836016
Remaining Compilation Queue : 0
Time to queue : count= 229, sum= 775960, min= 48, average= 3388.47, max= 130035 (milliseconds), maxTarget=SmallInteger>>printString
Time waiting in queue : count= 154, sum= 1660453, min= 0, average= 10782.16, max= 32399 (milliseconds), maxTarget=ListElement>>length <split-4482469c>
Time for compilation : count= 150, sum= 113352, min= 30, average= 755.68, max= 2957 (milliseconds), maxTarget=List>>talkWithX:withY:withZ: <split-c3fa05a>
Truffle Tier : count= 150, sum= 63518, min= 27, average= 423.46, max= 1473 (milliseconds), maxTarget=List>>talkWithX:withY:withZ: <split-7b44b63d>
Graal Tier : count= 150, sum= 26480, min= 1, average= 176.54, max= 823 (milliseconds), maxTarget=List>>talkWithX:withY:withZ: <split-c3fa05a>
Code Installation : count= 150, sum= 23352, min= 1, average= 155.68, max= 786 (milliseconds), maxTarget=List>>talkWithX:withY:withZ: <split-c3fa05a>
Truffle node count : count= 150, sum= 256714, min= 23, average= 1711.43, max= 6741, maxTarget=List>>talkWithX:withY:withZ: <split-7b44b63d>
Trivial : count= 150, sum= 89160, min= 8, average= 594.40, max= 2331, maxTarget=List>>talkWithX:withY:withZ: <split-7b44b63d>
Non Trivial : count= 150, sum= 167554, min= 15, average= 1117.03, max= 4410, maxTarget=List>>talkWithX:withY:withZ: <split-7b44b63d>
Monomorphic : count= 150, sum= 162670, min= 15, average= 1084.47, max= 4284, maxTarget=List>>talkWithX:withY:withZ: <split-7b44b63d>
Polymorphic : count= 150, sum= 4884, min= 0, average= 32.56, max= 126, maxTarget=List>>talkWithX:withY:withZ: <split-7b44b63d>
Megamorphic : count= 150, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=List>>isShorter:than: <split-644abb8f>
Truffle call count : count= 150, sum= 3781, min= 0, average= 25.21, max= 105, maxTarget=List>>talkWithX:withY:withZ: <split-7b44b63d>
Indirect : count= 150, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=List>>isShorter:than: <split-644abb8f>
Direct : count= 150, sum= 3781, min= 0, average= 25.21, max= 105, maxTarget=List>>talkWithX:withY:withZ: <split-7b44b63d>
Dispatched : count= 150, sum= 2185, min= 0, average= 14.57, max= 64, maxTarget=List>>talkWithX:withY:withZ: <split-7b44b63d>
Inlined : count= 150, sum= 1596, min= 0, average= 10.64, max= 48, maxTarget=List>>benchmark
---------- :
Cloned : count= 150, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=List>>isShorter:than: <split-644abb8f>
Not Cloned : count= 150, sum= 3781, min= 0, average= 25.21, max= 105, maxTarget=List>>talkWithX:withY:withZ: <split-7b44b63d>
Truffle loops : count= 150, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=List>>isShorter:than: <split-644abb8f>
Graal node count :
After Truffle Tier : count= 150, sum= 267198, min= 25, average= 1781.32, max= 7013, maxTarget=List>>talkWithX:withY:withZ: <split-7b44b63d>
After Graal Tier : count= 150, sum= 471441, min= 28, average= 3142.94, max= 12659, maxTarget=List>>talkWithX:withY:withZ: <split-c3fa05a>
Graal compilation result :
Code size : count= 150, sum= 1983961, min= 189, average= 13226.41, max= 54734, maxTarget=List>>talkWithX:withY:withZ: <split-c3fa05a>
Total frame size : count= 150, sum= 69824, min= 32, average= 465.49, max= 1888, maxTarget=List>>talkWithX:withY:withZ: <split-7b44b63d>
Exception handlers : count= 150, sum= 2199, min= 0, average= 14.66, max= 64, maxTarget=List>>talkWithX:withY:withZ: <split-7b44b63d>
Infopoints : count= 150, sum= 29116, min= 4, average= 194.11, max= 793, maxTarget=List>>talkWithX:withY:withZ: <split-7b44b63d>
CALL : count= 150, sum= 20893, min= 4, average= 139.29, max= 569, maxTarget=List>>talkWithX:withY:withZ: <split-7b44b63d>
IMPLICIT_EXCEPTION : count= 150, sum= 7355, min= 0, average= 49.03, max= 203, maxTarget=List>>talkWithX:withY:withZ: <split-7b44b63d>
SAFEPOINT : count= 150, sum= 868, min= 0, average= 5.79, max= 21, maxTarget=List>>talkWithX:withY:withZ: <split-7b44b63d>
Marks : count= 150, sum= 4014, min= 6, average= 26.76, max= 91, maxTarget=List>>talkWithX:withY:withZ: <split-7b44b63d>
Data references : count= 150, sum= 24233, min= 3, average= 161.55, max= 636, maxTarget=List>>talkWithX:withY:withZ: <split-c3fa05a>
Raw
b5e925dc-Mandelbrot.trace.log
# Mandelbrot (iterations: 250, problem size: 500) on Mon Jul 26 08:10:06 UTC 2021
# `/home/fniephaus/bin/graalvm-ee-java11-21.2.0/bin/trufflesqueak --experimental-options --smalltalk.disable-startup --smalltalk.disable-interrupts --engine.Mode=default --engine.MultiTier=false --engine.DynamicCompilationThresholds=false --engine.TraceCompilation --engine.CompilationStatistics --log.file="/home/fniephaus/dev/are-we-fast-yet/traces/Mandelbrot.trace.log" --quiet --code "FileStream startUp: true. Harness new run: #(nil 'Mandelbrot' 250 500)" /home/fniephaus/dev/are-we-fast-yet/benchmarks/Smalltalk/AWFY64-sista-fbc.image`
[engine] opt done Integer>><< |AST 25|Tier 2|Time 103( 88+15 )ms|Inlined 0Y 0N|IR 76/ 114|CodeSize 444|Addr 0x7f59637d4690|Src n/a
[engine] opt done Mandelbrot>>mandelbrot: |AST 500|Tier 2|Time 403( 283+120 )ms|Inlined 2Y 0N|IR 550/ 1811|CodeSize 13375|Addr 0x7f59637f4d10|Src n/a
[engine] opt done String class>>new: |AST 51|Tier 2|Time 186( 166+20 )ms|Inlined 2Y 1N|IR 213/ 474|CodeSize 1885|Addr 0x7f5963815f10|Src n/a
[engine] opt done SmallInteger>>printString |AST 152|Tier 2|Time 254( 232+22 )ms|Inlined 4Y 1N|IR 362/ 385|CodeSize 1158|Addr 0x7f5963818390|Src n/a
[engine] Truffle runtime statistics for engine 3
Compilations : 5
Success : 4
Temporary Bailouts : 0
Permanent Bailouts : 0
Failed : 0
Interrupted : 1
Invalidated : 0
Queues : 5
Dequeues : 0
Splits : 225
Compilation Accuracy : 1.000000
Queue Accuracy : 1.000000
Compilation Utilization : 0.008493
Remaining Compilation Queue : 0
Time to queue : count= 5, sum= 305935, min= 179, average= 61187.13, max= 95854 (milliseconds), maxTarget=String class>>new:
Time waiting in queue : count= 5, sum= 54, min= 1, average= 10.90, max= 17 (milliseconds), maxTarget=Mandelbrot>>mandelbrot:
Time for compilation : count= 4, sum= 964, min= 114, average= 241.21, max= 405 (milliseconds), maxTarget=Mandelbrot>>mandelbrot:
Truffle Tier : count= 4, sum= 767, min= 87, average= 191.98, max= 282 (milliseconds), maxTarget=Mandelbrot>>mandelbrot:
Graal Tier : count= 4, sum= 126, min= 11, average= 31.58, max= 82 (milliseconds), maxTarget=Mandelbrot>>mandelbrot:
Code Installation : count= 4, sum= 70, min= 6, average= 17.65, max= 39 (milliseconds), maxTarget=Mandelbrot>>mandelbrot:
Truffle node count : count= 4, sum= 1777, min= 40, average= 444.25, max= 892, maxTarget=Mandelbrot>>mandelbrot:
Trivial : count= 4, sum= 687, min= 15, average= 171.75, max= 342, maxTarget=Mandelbrot>>mandelbrot:
Non Trivial : count= 4, sum= 1090, min= 25, average= 272.50, max= 550, maxTarget=Mandelbrot>>mandelbrot:
Monomorphic : count= 4, sum= 1072, min= 25, average= 268.00, max= 550, maxTarget=Mandelbrot>>mandelbrot:
Polymorphic : count= 4, sum= 18, min= 0, average= 4.50, max= 9, maxTarget=String class>>new:
Megamorphic : count= 4, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Integer>><<
Truffle call count : count= 4, sum= 10, min= 0, average= 2.50, max= 5, maxTarget=SmallInteger>>printString
Indirect : count= 4, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Integer>><<
Direct : count= 4, sum= 10, min= 0, average= 2.50, max= 5, maxTarget=SmallInteger>>printString
Dispatched : count= 4, sum= 2, min= 0, average= 0.50, max= 1, maxTarget=String class>>new:
Inlined : count= 4, sum= 8, min= 0, average= 2.00, max= 4, maxTarget=SmallInteger>>printString
---------- :
Cloned : count= 4, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Integer>><<
Not Cloned : count= 4, sum= 10, min= 0, average= 2.50, max= 5, maxTarget=SmallInteger>>printString
Truffle loops : count= 4, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Integer>><<
Graal node count :
After Truffle Tier : count= 4, sum= 1201, min= 76, average= 300.25, max= 550, maxTarget=Mandelbrot>>mandelbrot:
After Graal Tier : count= 4, sum= 2784, min= 114, average= 696.00, max= 1811, maxTarget=Mandelbrot>>mandelbrot:
Graal compilation result :
Code size : count= 4, sum= 16862, min= 444, average= 4215.50, max= 13375, maxTarget=Mandelbrot>>mandelbrot:
Total frame size : count= 4, sum= 784, min= 32, average= 196.00, max= 640, maxTarget=Mandelbrot>>mandelbrot:
Exception handlers : count= 4, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Integer>><<
Infopoints : count= 4, sum= 145, min= 8, average= 36.25, max= 101, maxTarget=Mandelbrot>>mandelbrot:
CALL : count= 4, sum= 109, min= 7, average= 27.25, max= 73, maxTarget=Mandelbrot>>mandelbrot:
IMPLICIT_EXCEPTION : count= 4, sum= 30, min= 1, average= 7.50, max= 22, maxTarget=Mandelbrot>>mandelbrot:
SAFEPOINT : count= 4, sum= 6, min= 0, average= 1.50, max= 6, maxTarget=Mandelbrot>>mandelbrot:
Marks : count= 4, sum= 30, min= 6, average= 7.50, max= 12, maxTarget=Mandelbrot>>mandelbrot:
Data references : count= 4, sum= 154, min= 4, average= 38.50, max= 108, maxTarget=Mandelbrot>>mandelbrot:
Raw
b5e925dc-NBody.trace.log
# NBody (iterations: 250, problem size: 250000) on Mon Jul 26 08:09:07 UTC 2021
# `/home/fniephaus/bin/graalvm-ee-java11-21.2.0/bin/trufflesqueak --experimental-options --smalltalk.disable-startup --smalltalk.disable-interrupts --engine.Mode=default --engine.MultiTier=false --engine.DynamicCompilationThresholds=false --engine.TraceCompilation --engine.CompilationStatistics --log.file="/home/fniephaus/dev/are-we-fast-yet/traces/NBody.trace.log" --quiet --code "FileStream startUp: true. Harness new run: #(nil 'NBody' 250 250000)" /home/fniephaus/dev/are-we-fast-yet/benchmarks/Smalltalk/AWFY64-sista-fbc.image`
[engine] opt done Body>>vx: |AST 11|Tier 2|Time 121( 98+23 )ms|Inlined 0Y 0N|IR 76/ 93|CodeSize 269|Addr 0x7fb7e77c1110|Src n/a
[engine] opt done Body>>vz: |AST 11|Tier 2|Time 115( 92+23 )ms|Inlined 0Y 0N|IR 76/ 93|CodeSize 269|Addr 0x7fb7e77c1e10|Src n/a
[engine] opt done Body>>vy: |AST 11|Tier 2|Time 118( 96+22 )ms|Inlined 0Y 0N|IR 76/ 93|CodeSize 269|Addr 0x7fb7e77c1790|Src n/a
[engine] opt done NBodySystem>>advance: |AST 187|Tier 2|Time 201( 190+11 )ms|Inlined 3Y 0N|IR 310/ 306|CodeSize 638|Addr 0x7fb7e77e2410|Src n/a
[engine] opt done SequenceableCollection>>do: <split-61533ae> |AST 73|Tier 2|Time 286( 234+52 )ms|Inlined 4Y 0N|IR 493/ 1685|CodeSize 5813|Addr 0x7fb7e77f5e10|Src n/a
[engine] opt done NBodySystem>>advance: |AST 816|Tier 2|Time 686( 578+108 )ms|Inlined 11Y 0N|IR 1341/ 2162|CodeSize 10479|Addr 0x7fb7e7801090|Src n/a
[engine] opt done NBody>>innerBenchmarkLoop: |AST 95|Tier 2|Time 2863(1829+1033)ms|Inlined 98Y 1N|IR 6161/11618|CodeSize 53939|Addr 0x7fb7e782aa90|Src n/a
[engine] opt done String class>>new: |AST 51|Tier 2|Time 182( 162+19 )ms|Inlined 2Y 1N|IR 213/ 474|CodeSize 1885|Addr 0x7fb7e785b990|Src n/a
[engine] opt done SmallInteger>>printString |AST 152|Tier 2|Time 243( 224+20 )ms|Inlined 4Y 1N|IR 362/ 377|CodeSize 1185|Addr 0x7fb7e7860d90|Src n/a
[engine] Truffle runtime statistics for engine 3
Compilations : 10
Success : 9
Temporary Bailouts : 0
Permanent Bailouts : 0
Failed : 0
Interrupted : 1
Invalidated : 0
Queues : 13
Dequeues : 3
Target inlined into only caller : 3
Splits : 233
Compilation Accuracy : 1.000000
Queue Accuracy : 0.769231
Compilation Utilization : 0.091706
Remaining Compilation Queue : 0
Time to queue : count= 13, sum= 114490, min= 85, average= 8806.99, max= 51970 (milliseconds), maxTarget=SmallInteger>>printString
Time waiting in queue : count= 10, sum= 243, min= 1, average= 24.39, max= 142 (milliseconds), maxTarget=NBodySystem>>advance:
Time for compilation : count= 9, sum= 4888, min= 136, average= 543.21, max= 2864 (milliseconds), maxTarget=NBody>>innerBenchmarkLoop:
Truffle Tier : count= 9, sum= 3503, min= 91, average= 389.29, max= 1829 (milliseconds), maxTarget=NBody>>innerBenchmarkLoop:
Graal Tier : count= 9, sum= 761, min= 6, average= 84.58, max= 582 (milliseconds), maxTarget=NBody>>innerBenchmarkLoop:
Code Installation : count= 9, sum= 624, min= 4, average= 69.35, max= 452 (milliseconds), maxTarget=NBody>>innerBenchmarkLoop:
Truffle node count : count= 9, sum= 12099, min= 19, average= 1344.33, max= 8572, maxTarget=NBody>>innerBenchmarkLoop:
Trivial : count= 9, sum= 4463, min= 8, average= 495.89, max= 3172, maxTarget=NBody>>innerBenchmarkLoop:
Non Trivial : count= 9, sum= 7636, min= 11, average= 848.44, max= 5400, maxTarget=NBody>>innerBenchmarkLoop:
Monomorphic : count= 9, sum= 7618, min= 11, average= 846.44, max= 5400, maxTarget=NBody>>innerBenchmarkLoop:
Polymorphic : count= 9, sum= 18, min= 0, average= 2.00, max= 9, maxTarget=String class>>new:
Megamorphic : count= 9, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Body>>vy:
Truffle call count : count= 9, sum= 125, min= 0, average= 13.89, max= 99, maxTarget=NBody>>innerBenchmarkLoop:
Indirect : count= 9, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Body>>vy:
Direct : count= 9, sum= 125, min= 0, average= 13.89, max= 99, maxTarget=NBody>>innerBenchmarkLoop:
Dispatched : count= 9, sum= 3, min= 0, average= 0.33, max= 1, maxTarget=NBody>>innerBenchmarkLoop:
Inlined : count= 9, sum= 122, min= 0, average= 13.56, max= 98, maxTarget=NBody>>innerBenchmarkLoop:
---------- :
Cloned : count= 9, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Body>>vy:
Not Cloned : count= 9, sum= 125, min= 0, average= 13.89, max= 99, maxTarget=NBody>>innerBenchmarkLoop:
Truffle loops : count= 9, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Body>>vy:
Graal node count :
After Truffle Tier : count= 9, sum= 9108, min= 76, average= 1012.00, max= 6161, maxTarget=NBody>>innerBenchmarkLoop:
After Graal Tier : count= 9, sum= 16901, min= 93, average= 1877.89, max= 11618, maxTarget=NBody>>innerBenchmarkLoop:
Graal compilation result :
Code size : count= 9, sum= 74746, min= 269, average= 8305.11, max= 53939, maxTarget=NBody>>innerBenchmarkLoop:
Total frame size : count= 9, sum= 1440, min= 32, average= 160.00, max= 608, maxTarget=NBody>>innerBenchmarkLoop:
Exception handlers : count= 9, sum= 1, min= 0, average= 0.11, max= 1, maxTarget=NBody>>innerBenchmarkLoop:
Infopoints : count= 9, sum= 561, min= 5, average= 62.33, max= 361, maxTarget=NBody>>innerBenchmarkLoop:
CALL : count= 9, sum= 431, min= 5, average= 47.89, max= 272, maxTarget=NBody>>innerBenchmarkLoop:
IMPLICIT_EXCEPTION : count= 9, sum= 122, min= 0, average= 13.56, max= 83, maxTarget=NBody>>innerBenchmarkLoop:
SAFEPOINT : count= 9, sum= 8, min= 0, average= 0.89, max= 6, maxTarget=NBody>>innerBenchmarkLoop:
Marks : count= 9, sum= 65, min= 6, average= 7.22, max= 14, maxTarget=NBody>>innerBenchmarkLoop:
Data references : count= 9, sum= 1559, min= 2, average= 173.22, max= 1371, maxTarget=NBody>>innerBenchmarkLoop:
Raw
b5e925dc-Permute.trace.log
# Permute (iterations: 250, problem size: 1000) on Mon Jul 26 08:20:02 UTC 2021
# `/home/fniephaus/bin/graalvm-ee-java11-21.2.0/bin/trufflesqueak --experimental-options --smalltalk.disable-startup --smalltalk.disable-interrupts --engine.Mode=default --engine.MultiTier=false --engine.DynamicCompilationThresholds=false --engine.TraceCompilation --engine.CompilationStatistics --log.file="/home/fniephaus/dev/are-we-fast-yet/traces/Permute.trace.log" --quiet --code "FileStream startUp: true. Harness new run: #(nil 'Permute' 250 1000)" /home/fniephaus/dev/are-we-fast-yet/benchmarks/Smalltalk/AWFY64-sista-fbc.image`
[engine] opt done Permute>>swap:with: |AST 74|Tier 2|Time 178( 155+23 )ms|Inlined 0Y 0N|IR 173/ 158|CodeSize 471|Addr 0x7fedc77c6290|Src n/a
[engine] opt done Permute>>permute: |AST 138|Tier 2|Time 1034( 712+322 )ms|Inlined 20Y 8N|IR 2982/ 4179|CodeSize 20582|Addr 0x7fedc77f5290|Src n/a
[engine] opt done SequenceableCollection>>atAllPut: |AST 72|Tier 2|Time 107( 90+16 )ms|Inlined 0Y 0N|IR 184/ 409|CodeSize 1621|Addr 0x7fedc780a410|Src n/a
[engine] opt done Permute>>verifyResult: |AST 15|Tier 2|Time 39( 36+2 )ms|Inlined 0Y 0N|IR 25/ 28|CodeSize 192|Addr 0x7fedc780e210|Src n/a
[engine] opt done ArrayedCollection class>>new:withAll: |AST 31|Tier 2|Time 138( 125+13 )ms|Inlined 1Y 0N|IR 237/ 291|CodeSize 960|Addr 0x7fedc7811910|Src n/a
[engine] opt done Permute>>benchmark |AST 49|Tier 2|Time 1107( 837+269 )ms|Inlined 23Y 8N|IR 3024/ 2969|CodeSize 11954|Addr 0x7fedc7820b90|Src n/a
[engine] opt done Benchmark>>innerBenchmarkLoop: |AST 59|Tier 2|Time 1147( 747+400 )ms|Inlined 25Y 8N|IR 3120/ 3756|CodeSize 15082|Addr 0x7fedc7831390|Src n/a
[engine] opt done String class>>new: |AST 51|Tier 2|Time 187( 169+18 )ms|Inlined 2Y 1N|IR 213/ 474|CodeSize 1885|Addr 0x7fedc784e010|Src n/a
[engine] opt done SmallInteger>>printString |AST 152|Tier 2|Time 254( 234+19 )ms|Inlined 4Y 1N|IR 362/ 377|CodeSize 1185|Addr 0x7fedc7853090|Src n/a
[engine] Truffle runtime statistics for engine 3
Compilations : 10
Success : 9
Temporary Bailouts : 0
Permanent Bailouts : 0
Failed : 0
Interrupted : 1
Invalidated : 0
Queues : 10
Dequeues : 0
Splits : 225
Compilation Accuracy : 1.000000
Queue Accuracy : 1.000000
Compilation Utilization : 0.075951
Remaining Compilation Queue : 0
Time to queue : count= 10, sum= 120860, min= 36, average= 12086.01, max= 54121 (milliseconds), maxTarget=SmallInteger>>printString
Time waiting in queue : count= 10, sum= 62, min= 1, average= 6.29, max= 26 (milliseconds), maxTarget=String class>>new:
Time for compilation : count= 9, sum= 4221, min= 39, average= 469.05, max= 1147 (milliseconds), maxTarget=Benchmark>>innerBenchmarkLoop:
Truffle Tier : count= 9, sum= 3104, min= 36, average= 345.00, max= 837 (milliseconds), maxTarget=Permute>>benchmark
Graal Tier : count= 9, sum= 700, min= 1, average= 77.81, max= 264 (milliseconds), maxTarget=Benchmark>>innerBenchmarkLoop:
Code Installation : count= 9, sum= 416, min= 1, average= 46.24, max= 136 (milliseconds), maxTarget=Benchmark>>innerBenchmarkLoop:
Truffle node count : count= 9, sum= 11794, min= 23, average= 1310.44, max= 3662, maxTarget=Benchmark>>innerBenchmarkLoop:
Trivial : count= 9, sum= 4631, min= 8, average= 514.56, max= 1434, maxTarget=Benchmark>>innerBenchmarkLoop:
Non Trivial : count= 9, sum= 7163, min= 15, average= 795.89, max= 2228, maxTarget=Benchmark>>innerBenchmarkLoop:
Monomorphic : count= 9, sum= 7137, min= 15, average= 793.00, max= 2226, maxTarget=Benchmark>>innerBenchmarkLoop:
Polymorphic : count= 9, sum= 26, min= 0, average= 2.89, max= 9, maxTarget=String class>>new:
Megamorphic : count= 9, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Permute>>swap:with:
Truffle call count : count= 9, sum= 101, min= 0, average= 11.22, max= 33, maxTarget=Benchmark>>innerBenchmarkLoop:
Indirect : count= 9, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Permute>>swap:with:
Direct : count= 9, sum= 101, min= 0, average= 11.22, max= 33, maxTarget=Benchmark>>innerBenchmarkLoop:
Dispatched : count= 9, sum= 26, min= 0, average= 2.89, max= 8, maxTarget=Permute>>permute:
Inlined : count= 9, sum= 75, min= 0, average= 8.33, max= 25, maxTarget=Benchmark>>innerBenchmarkLoop:
---------- :
Cloned : count= 9, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Permute>>swap:with:
Not Cloned : count= 9, sum= 101, min= 0, average= 11.22, max= 33, maxTarget=Benchmark>>innerBenchmarkLoop:
Truffle loops : count= 9, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Permute>>swap:with:
Graal node count :
After Truffle Tier : count= 9, sum= 10320, min= 25, average= 1146.67, max= 3120, maxTarget=Benchmark>>innerBenchmarkLoop:
After Graal Tier : count= 9, sum= 12641, min= 28, average= 1404.56, max= 4179, maxTarget=Permute>>permute:
Graal compilation result :
Code size : count= 9, sum= 53932, min= 192, average= 5992.44, max= 20582, maxTarget=Permute>>permute:
Total frame size : count= 9, sum= 1424, min= 32, average= 158.22, max= 544, maxTarget=Permute>>permute:
Exception handlers : count= 9, sum= 25, min= 0, average= 2.78, max= 9, maxTarget=Benchmark>>innerBenchmarkLoop:
Infopoints : count= 9, sum= 579, min= 4, average= 64.33, max= 215, maxTarget=Permute>>permute:
CALL : count= 9, sum= 420, min= 4, average= 46.67, max= 151, maxTarget=Permute>>permute:
IMPLICIT_EXCEPTION : count= 9, sum= 149, min= 0, average= 16.56, max= 57, maxTarget=Permute>>permute:
SAFEPOINT : count= 9, sum= 10, min= 0, average= 1.11, max= 7, maxTarget=Permute>>permute:
Marks : count= 9, sum= 89, min= 6, average= 9.89, max= 21, maxTarget=Permute>>permute:
Data references : count= 9, sum= 479, min= 3, average= 53.22, max= 148, maxTarget=Permute>>permute:
Raw
b5e925dc-Queens.trace.log
# Queens (iterations: 250, problem size: 1000) on Mon Jul 26 08:27:08 UTC 2021
# `/home/fniephaus/bin/graalvm-ee-java11-21.2.0/bin/trufflesqueak --experimental-options --smalltalk.disable-startup --smalltalk.disable-interrupts --engine.Mode=default --engine.MultiTier=false --engine.DynamicCompilationThresholds=false --engine.TraceCompilation --engine.CompilationStatistics --log.file="/home/fniephaus/dev/are-we-fast-yet/traces/Queens.trace.log" --quiet --code "FileStream startUp: true. Harness new run: #(nil 'Queens' 250 1000)" /home/fniephaus/dev/are-we-fast-yet/benchmarks/Smalltalk/AWFY64-sista-fbc.image`
[engine] opt done Queens>>row:column:put: |AST 83|Tier 2|Time 109( 100+10 )ms|Inlined 0Y 0N|IR 188/ 291|CodeSize 1113|Addr 0x7f71b35bd310|Src n/a
[engine] opt done Queens>>row:column: |AST 77|Tier 2|Time 184( 157+27 )ms|Inlined 0Y 0N|IR 216/ 259|CodeSize 1094|Addr 0x7f71b35bbd10|Src n/a
[engine] opt done SequenceableCollection>>atAllPut: |AST 72|Tier 2|Time 132( 101+32 )ms|Inlined 0Y 0N|IR 252/ 713|CodeSize 2911|Addr 0x7f71b35d5b10|Src n/a
[engine] opt done ArrayedCollection class>>new:withAll: |AST 31|Tier 2|Time 149( 112+37 )ms|Inlined 1Y 0N|IR 302/ 678|CodeSize 2331|Addr 0x7f71b35f6490|Src n/a
[engine] opt done Queens>>placeQueen: |AST 149|Tier 2|Time 769( 519+250 )ms|Inlined 11Y 1N|IR 2084/ 2673|CodeSize 14929|Addr 0x7f71b35f8690|Src n/a
[engine] opt done True>>and: |AST 17|Tier 2|Time 41( 39+2 )ms|Inlined 0Y 0N|IR 23/ 30|CodeSize 200|Addr 0x7f71b3605d90|Src n/a
[engine] opt done Queens>>verifyResult: |AST 9|Tier 2|Time 33( 31+2 )ms|Inlined 0Y 0N|IR 19/ 22|CodeSize 154|Addr 0x7f71b360f510|Src n/a
[engine] opt done Queens>>queens |AST 100|Tier 2|Time 1154( 622+532 )ms|Inlined 20Y 1N|IR 2848/ 3471|CodeSize 15889|Addr 0x7f71b3619d90|Src n/a
[engine] opt done Benchmark>>innerBenchmarkLoop: |AST 59|Tier 2|Time 722( 709+13 )ms|Inlined 1Y 1N|IR 138/ 445|CodeSize 1702|Addr 0x7f71b3624610|Src n/a
[engine] opt done Queens>>benchmark |AST 68|Tier 2|Time 1381( 664+717 )ms|Inlined 22Y 1N|IR 2949/ 4822|CodeSize 23460|Addr 0x7f71b3625b10|Src n/a
[engine] opt done String class>>new: |AST 51|Tier 2|Time 178( 159+19 )ms|Inlined 2Y 1N|IR 213/ 474|CodeSize 1885|Addr 0x7f71b363b810|Src n/a
[engine] opt done SmallInteger>>printString |AST 152|Tier 2|Time 247( 228+20 )ms|Inlined 4Y 1N|IR 362/ 377|CodeSize 1185|Addr 0x7f71b363fa90|Src n/a
[engine] Truffle runtime statistics for engine 3
Compilations : 13
Success : 12
Temporary Bailouts : 0
Permanent Bailouts : 0
Failed : 0
Interrupted : 1
Invalidated : 0
Queues : 13
Dequeues : 0
Splits : 225
Compilation Accuracy : 1.000000
Queue Accuracy : 1.000000
Compilation Utilization : 0.110087
Remaining Compilation Queue : 0
Time to queue : count= 13, sum= 102501, min= 40, average= 7884.76, max= 45624 (milliseconds), maxTarget=SmallInteger>>printString
Time waiting in queue : count= 13, sum= 56, min= 0, average= 4.36, max= 21 (milliseconds), maxTarget=String class>>new:
Time for compilation : count= 12, sum= 5145, min= 35, average= 428.76, max= 1381 (milliseconds), maxTarget=Queens>>benchmark
Truffle Tier : count= 12, sum= 3438, min= 30, average= 286.53, max= 709 (milliseconds), maxTarget=Benchmark>>innerBenchmarkLoop:
Graal Tier : count= 12, sum= 1242, min= 1, average= 103.58, max= 540 (milliseconds), maxTarget=Queens>>benchmark
Code Installation : count= 12, sum= 463, min= 1, average= 38.66, max= 177 (milliseconds), maxTarget=Queens>>benchmark
Truffle node count : count= 12, sum= 9112, min= 13, average= 759.33, max= 2885, maxTarget=Queens>>benchmark
Trivial : count= 12, sum= 3531, min= 4, average= 294.25, max= 1112, maxTarget=Queens>>benchmark
Non Trivial : count= 12, sum= 5581, min= 9, average= 465.08, max= 1773, maxTarget=Queens>>benchmark
Monomorphic : count= 12, sum= 5543, min= 9, average= 461.92, max= 1765, maxTarget=Queens>>benchmark
Polymorphic : count= 12, sum= 38, min= 0, average= 3.17, max= 9, maxTarget=String class>>new:
Megamorphic : count= 12, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Queens>>row:column:
Truffle call count : count= 12, sum= 67, min= 0, average= 5.58, max= 23, maxTarget=Queens>>benchmark
Indirect : count= 12, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Queens>>row:column:
Direct : count= 12, sum= 67, min= 0, average= 5.58, max= 23, maxTarget=Queens>>benchmark
Dispatched : count= 12, sum= 6, min= 0, average= 0.50, max= 1, maxTarget=Queens>>placeQueen:
Inlined : count= 12, sum= 61, min= 0, average= 5.08, max= 22, maxTarget=Queens>>benchmark
---------- :
Cloned : count= 12, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Queens>>row:column:
Not Cloned : count= 12, sum= 67, min= 0, average= 5.58, max= 23, maxTarget=Queens>>benchmark
Truffle loops : count= 12, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Queens>>row:column:
Graal node count :
After Truffle Tier : count= 12, sum= 9594, min= 19, average= 799.50, max= 2949, maxTarget=Queens>>benchmark
After Graal Tier : count= 12, sum= 14255, min= 22, average= 1187.92, max= 4822, maxTarget=Queens>>benchmark
Graal compilation result :
Code size : count= 12, sum= 66853, min= 154, average= 5571.08, max= 23460, maxTarget=Queens>>benchmark
Total frame size : count= 12, sum= 1856, min= 32, average= 154.67, max= 464, maxTarget=Queens>>placeQueen:
Exception handlers : count= 12, sum= 5, min= 0, average= 0.42, max= 2, maxTarget=Benchmark>>innerBenchmarkLoop:
Infopoints : count= 12, sum= 822, min= 4, average= 68.50, max= 260, maxTarget=Queens>>benchmark
CALL : count= 12, sum= 554, min= 4, average= 46.17, max= 173, maxTarget=Queens>>benchmark
IMPLICIT_EXCEPTION : count= 12, sum= 222, min= 0, average= 18.50, max= 73, maxTarget=Queens>>benchmark
SAFEPOINT : count= 12, sum= 46, min= 0, average= 3.83, max= 14, maxTarget=Queens>>placeQueen:
Marks : count= 12, sum= 125, min= 6, average= 10.42, max= 22, maxTarget=Queens>>queens
Data references : count= 12, sum= 864, min= 1, average= 72.00, max= 324, maxTarget=Queens>>benchmark
Raw
b5e925dc-Richards.trace.log
# Richards (iterations: 250, problem size: 100) on Mon Jul 26 08:16:13 UTC 2021
# `/home/fniephaus/bin/graalvm-ee-java11-21.2.0/bin/trufflesqueak --experimental-options --smalltalk.disable-startup --smalltalk.disable-interrupts --engine.Mode=default --engine.MultiTier=false --engine.DynamicCompilationThresholds=false --engine.TraceCompilation --engine.CompilationStatistics --log.file="/home/fniephaus/dev/are-we-fast-yet/traces/Richards.trace.log" --quiet --code "FileStream startUp: true. Harness new run: #(nil 'Richards' 250 100)" /home/fniephaus/dev/are-we-fast-yet/benchmarks/Smalltalk/AWFY64-sista-fbc.image`
[engine] opt done TaskState>>isTaskHoldingOrWaiting |AST 32|Tier 2|Time 139( 110+29 )ms|Inlined 0Y 0N|IR 125/ 100|CodeSize 334|Addr 0x7f907f7ce590|Src n/a
[engine] opt done TaskState>>isWaitingWithPacket |AST 31|Tier 2|Time 99( 68+31 )ms|Inlined 0Y 0N|IR 84/ 103|CodeSize 340|Addr 0x7f907f7cdf90|Src n/a
[engine] opt done Packet>>link: |AST 11|Tier 2|Time 51( 47+4 )ms|Inlined 0Y 0N|IR 61/ 183|CodeSize 459|Addr 0x7f907f7d9f90|Src n/a
[engine] opt done Scheduler>>findTask: |AST 45|Tier 2|Time 113( 104+9 )ms|Inlined 0Y 0N|IR 70/ 133|CodeSize 555|Addr 0x7f907f7e4b10|Src n/a
[engine] opt done TaskControlBlock>>runTask |AST 121|Tier 2|Time 273( 236+37 )ms|Inlined 3Y 0N|IR 538/ 1471|CodeSize 4639|Addr 0x7f907f7e8f90|Src n/a
[engine] opt done Packet>>identity: |AST 11|Tier 2|Time 39( 36+3 )ms|Inlined 0Y 0N|IR 74/ 90|CodeSize 256|Addr 0x7f907f7ed610|Src n/a
[engine] opt done TaskState>>taskWaiting: |AST 11|Tier 2|Time 40( 36+3 )ms|Inlined 0Y 0N|IR 76/ 89|CodeSize 260|Addr 0x7f907f7f3b90|Src n/a
[engine] opt done Scheduler>>wait |AST 27|Tier 2|Time 62( 57+5 )ms|Inlined 1Y 0N|IR 126/ 139|CodeSize 321|Addr 0x7f907f7ff190|Src n/a
[engine] opt done Scheduler>>createDevice:priority:work:state: |AST 157|Tier 2|Time 390( 347+42 )ms|Inlined 15Y 0N|IR 1140/ 1278|CodeSize 3647|Addr 0x7f907f818f90|Src n/a
[engine] opt done Scheduler>>queuePacket: |AST 127|Tier 2|Time 278( 238+40 )ms|Inlined 8Y 0N|IR 651/ 1022|CodeSize 2786|Addr 0x7f907f826290|Src n/a
[engine] opt done Packet>>datum: |AST 11|Tier 2|Time 28( 26+3 )ms|Inlined 0Y 0N|IR 74/ 90|CodeSize 256|Addr 0x7f907f829090|Src n/a
[engine] opt done TaskControlBlock>>addInput:checkPriority: |AST 97|Tier 2|Time 144( 121+24 )ms|Inlined 4Y 0N|IR 399/ 794|CodeSize 2269|Addr 0x7f907f829d90|Src n/a
[engine] opt done TaskState>>taskHolding: |AST 11|Tier 2|Time 30( 28+3 )ms|Inlined 0Y 0N|IR 76/ 89|CodeSize 260|Addr 0x7f907f82c210|Src n/a
[engine] opt done RBObject>>append:head: |AST 118|Tier 2|Time 95( 85+10 )ms|Inlined 2Y 0N|IR 216/ 414|CodeSize 1250|Addr 0x7f907f82eb10|Src n/a
[engine] opt deopt TaskControlBlock>>runTask |AST 121|Src n/a
[engine] opt inv. TaskControlBlock>>runTask |AST 121|Calls/Thres 6362/ 3|CallsAndLoop/Thres 6362/ 1000|Src n/a|Reason Profiled Return Type
[engine] opt done Scheduler>>createHandler:priority:work:state: |AST 349|Tier 2|Time 828( 590+238 )ms|Inlined 34Y 0N|IR 2370/ 3827|CodeSize 12796|Addr 0x7f907f83da10|Src n/a
[engine] opt done Scheduler>>createWorker:priority:work:state: |AST 319|Tier 2|Time 497( 427+70 )ms|Inlined 19Y 0N|IR 1338/ 1566|CodeSize 4269|Addr 0x7f907f853b10|Src n/a
[engine] opt done TaskControlBlock>>runTask |AST 121|Tier 2|Time 159( 127+32 )ms|Inlined 3Y 0N|IR 626/ 1489|CodeSize 4668|Addr 0x7f907f858f10|Src n/a
[engine] opt done IdleTaskDataRecord>>count: |AST 11|Tier 2|Time 30( 27+3 )ms|Inlined 0Y 0N|IR 73/ 90|CodeSize 256|Addr 0x7f907f85ba90|Src n/a
[engine] opt done IdleTaskDataRecord>>control: |AST 11|Tier 2|Time 33( 30+3 )ms|Inlined 0Y 0N|IR 73/ 90|CodeSize 256|Addr 0x7f907f85c710|Src n/a
[engine] opt done WorkerTaskDataRecord>>count: |AST 11|Tier 2|Time 31( 28+3 )ms|Inlined 0Y 0N|IR 73/ 90|CodeSize 256|Addr 0x7f907f861310|Src n/a
[engine] opt done TaskState>>running |AST 16|Tier 2|Time 38( 32+6 )ms|Inlined 0Y 0N|IR 215/ 189|CodeSize 650|Addr 0x7f907f866410|Src n/a
[engine] opt done Scheduler>>release: |AST 93|Tier 2|Time 114( 107+7 )ms|Inlined 2Y 0N|IR 191/ 255|CodeSize 741|Addr 0x7f907f867210|Src n/a
[engine] opt done RBObject class>>DeviceA |AST 8|Tier 2|Time 22( 20+2 )ms|Inlined 0Y 0N|IR 18/ 20|CodeSize 156|Addr 0x7f907f867e90|Src n/a
[engine] opt done Scheduler>>createIdler:priority:work:state: |AST 236|Tier 2|Time 296( 266+30 )ms|Inlined 13Y 0N|IR 820/ 784|CodeSize 1734|Addr 0x7f907f868710|Src n/a
[engine] opt done RBObject class>>DeviceB |AST 8|Tier 2|Time 23( 21+2 )ms|Inlined 0Y 0N|IR 18/ 20|CodeSize 156|Addr 0x7f907f86a990|Src n/a
[engine] opt done DeviceTaskDataRecord>>pending: |AST 11|Tier 2|Time 34( 30+4 )ms|Inlined 0Y 0N|IR 61/ 183|CodeSize 459|Addr 0x7f907f86ad90|Src n/a
[engine] opt done TaskState>>packetPending: |AST 11|Tier 2|Time 33( 30+3 )ms|Inlined 0Y 0N|IR 74/ 87|CodeSize 250|Addr 0x7f907f86b510|Src n/a
[engine] opt done RBObject class>>HandlerA |AST 8|Tier 2|Time 22( 20+2 )ms|Inlined 0Y 0N|IR 18/ 20|CodeSize 156|Addr 0x7f907f86c610|Src n/a
[engine] opt done TaskState>>packetPending |AST 18|Tier 2|Time 35( 32+3 )ms|Inlined 0Y 0N|IR 128/ 125|CodeSize 338|Addr 0x7f907f86ca10|Src n/a
[engine] opt done RBObject class>>HandlerB |AST 8|Tier 2|Time 23( 21+2 )ms|Inlined 0Y 0N|IR 18/ 20|CodeSize 156|Addr 0x7f907f86d210|Src n/a
[engine] opt done Scheduler>>holdSelf |AST 49|Tier 2|Time 68( 64+4 )ms|Inlined 1Y 0N|IR 170/ 165|CodeSize 428|Addr 0x7f907f86ec10|Src n/a
[engine] opt done Scheduler>>schedule |AST 108|Tier 2|Time 264( 161+103 )ms|Inlined 5Y 0N|IR 989/ 2677|CodeSize 9875|Addr 0x7f907f871790|Src n/a
[engine] opt done SequenceableCollection>>atAllPut: |AST 72|Tier 2|Time 86( 67+19 )ms|Inlined 0Y 0N|IR 206/ 481|CodeSize 2166|Addr 0x7f907f878c90|Src n/a
[engine] opt done SequenceableCollection>>atAllPut: |AST 72|Tier 2|Time 85( 65+20 )ms|Inlined 0Y 0N|IR 206/ 512|CodeSize 2184|Addr 0x7f907f87bb90|Src n/a
[engine] opt done ArrayedCollection class>>new:withAll: |AST 31|Tier 2|Time 134( 104+30 )ms|Inlined 1Y 0N|IR 258/ 676|CodeSize 2460|Addr 0x7f907f87eb10|Src n/a
[engine] opt done Behavior>>new <split-301d8120> |AST 26|Tier 2|Time 80( 77+3 )ms|Inlined 0Y 0N|IR 33/ 99|CodeSize 416|Addr 0x7f907f881d10|Src n/a
[engine] opt done Packet class>>create:identity:kind: |AST 35|Tier 2|Time 191( 178+13 )ms|Inlined 4Y 0N|IR 310/ 431|CodeSize 1432|Addr 0x7f907f882610|Src n/a
[engine] opt done Scheduler>>createPacket:identity:kind: |AST 29|Tier 2|Time 199( 185+14 )ms|Inlined 5Y 0N|IR 351/ 433|CodeSize 1445|Addr 0x7f907f883590|Src n/a
[engine] opt done Behavior>>new <split-6a74d228> |AST 26|Tier 2|Time 54( 48+6 )ms|Inlined 0Y 0N|IR 94/ 376|CodeSize 1303|Addr 0x7f907f885c10|Src n/a
[engine] opt done TaskControlBlock class>>link:create:priority:initialWorkQueue:initialState:function:privateData:|AST 47|Tier 2|Time 140( 125+15 )ms|Inlined 5Y 0N|IR 425/ 709|CodeSize 2185|Addr 0x7f907f888990|Src n/a
[engine] opt done Scheduler>>createTask:priority:work:state:function:data: |AST 69|Tier 2|Time 196( 163+33 )ms|Inlined 6Y 0N|IR 581/ 1378|CodeSize 4742|Addr 0x7f907f88a410|Src n/a
[engine] opt done TaskState>>waitingWithPacket |AST 17|Tier 2|Time 43( 39+4 )ms|Inlined 0Y 0N|IR 133/ 154|CodeSize 401|Addr 0x7f907f88fc10|Src n/a
[engine] opt done Behavior>>new <split-3e134896> |AST 26|Tier 2|Time 54( 51+3 )ms|Inlined 0Y 0N|IR 33/ 99|CodeSize 416|Addr 0x7f907f890510|Src n/a
[engine] opt done TaskState class>>waitingWithPacket |AST 26|Tier 2|Time 84( 78+7 )ms|Inlined 2Y 0N|IR 166/ 250|CodeSize 562|Addr 0x7f907f891a10|Src n/a
[engine] opt done HandlerTaskDataRecord class>>create |AST 26|Tier 2|Time 82( 78+4 )ms|Inlined 2Y 0N|IR 42/ 117|CodeSize 462|Addr 0x7f907f892710|Src n/a
[engine] opt done DeviceTaskDataRecord>>create |AST 19|Tier 2|Time 54( 45+9 )ms|Inlined 0Y 0N|IR 67/ 185|CodeSize 482|Addr 0x7f907f892d90|Src n/a
[engine] opt done Scheduler>>initialize |AST 77|Tier 2|Time 149( 139+10 )ms|Inlined 2Y 0N|IR 341/ 490|CodeSize 1281|Addr 0x7f907f893590|Src n/a
[engine] opt done Behavior>>new <split-c3fa05a> |AST 26|Tier 2|Time 48( 45+3 )ms|Inlined 0Y 0N|IR 33/ 99|CodeSize 416|Addr 0x7f907f894910|Src n/a
[engine] opt done TaskState class>>waiting |AST 26|Tier 2|Time 79( 72+7 )ms|Inlined 2Y 0N|IR 166/ 250|CodeSize 562|Addr 0x7f907f894f10|Src n/a
[engine] opt done Scheduler>>createHandler:priority:work:state: |AST 55|Tier 2|Time 296( 224+71 )ms|Inlined 10Y 0N|IR 819/ 2987|CodeSize 11960|Addr 0x7f907f896910|Src n/a
[engine] opt done DeviceTaskDataRecord class>>create |AST 26|Tier 2|Time 74( 70+3 )ms|Inlined 2Y 0N|IR 42/ 117|CodeSize 462|Addr 0x7f907f89c290|Src n/a
[engine] opt done Scheduler>>createDevice:priority:work:state: |AST 55|Tier 2|Time 270( 200+70 )ms|Inlined 10Y 0N|IR 819/ 2987|CodeSize 11960|Addr 0x7f907f89e790|Src n/a
[engine] opt done Behavior>>new <split-2b87581> |AST 28|Tier 2|Time 210( 201+8 )ms|Inlined 3Y 0N|IR 226/ 414|CodeSize 1390|Addr 0x7f907f8a4390|Src n/a
[engine] opt done WorkerTaskDataRecord>>create |AST 26|Tier 2|Time 62( 58+3 )ms|Inlined 1Y 0N|IR 104/ 110|CodeSize 313|Addr 0x7f907f8a5210|Src n/a
[engine] opt done Scheduler class>>new |AST 26|Tier 2|Time 278( 267+11 )ms|Inlined 7Y 0N|IR 380/ 414|CodeSize 1390|Addr 0x7f907f8a5990|Src n/a
[engine] opt done Richards>>benchmark |AST 30|Tier 2|Time 301( 286+15 )ms|Inlined 8Y 1N|IR 447/ 686|CodeSize 2281|Addr 0x7f907f8a6510|Src n/a
[engine] opt done Behavior>>new <split-693e4d19> |AST 26|Tier 2|Time 49( 46+3 )ms|Inlined 0Y 0N|IR 33/ 99|CodeSize 416|Addr 0x7f907f8a7790|Src n/a
[engine] opt done TaskState class>>running |AST 26|Tier 2|Time 83( 73+9 )ms|Inlined 2Y 0N|IR 220/ 273|CodeSize 710|Addr 0x7f907f8a7d90|Src n/a
[engine] opt done IdleTaskDataRecord class>>create |AST 26|Tier 2|Time 71( 68+3 )ms|Inlined 2Y 0N|IR 35/ 101|CodeSize 428|Addr 0x7f907f8aa610|Src n/a
[engine] opt done Scheduler>>createIdler:priority:work:state: |AST 55|Tier 2|Time 280( 209+70 )ms|Inlined 10Y 0N|IR 811/ 2972|CodeSize 12223|Addr 0x7f907f8aac90|Src n/a
[engine] opt done WorkerTaskDataRecord class>>create |AST 26|Tier 2|Time 79( 75+4 )ms|Inlined 3Y 0N|IR 43/ 118|CodeSize 468|Addr 0x7f907f8b0690|Src n/a
[engine] opt done Scheduler>>createWorker:priority:work:state: |AST 55|Tier 2|Time 278( 208+70 )ms|Inlined 11Y 0N|IR 820/ 2989|CodeSize 12106|Addr 0x7f907f8b0d10|Src n/a
[engine] opt done Richards>>verifyResult: |AST 9|Tier 2|Time 23( 21+2 )ms|Inlined 0Y 0N|IR 19/ 22|CodeSize 154|Addr 0x7f907f8b6d90|Src n/a
[engine] opt done True>>and: |AST 17|Tier 2|Time 36( 33+2 )ms|Inlined 0Y 0N|IR 23/ 30|CodeSize 200|Addr 0x7f907f8b7b90|Src n/a
[engine] opt done Benchmark>>innerBenchmarkLoop: |AST 59|Tier 2|Time 276( 242+34 )ms|Inlined 10Y 1N|IR 547/ 1416|CodeSize 4991|Addr 0x7f907f8b8710|Src n/a
[engine] opt done Scheduler>>start |AST 698|Tier 2|Time 2064(1565+499 )ms|Inlined 93Y 7N|IR 4856/10483|CodeSize 42189|Addr 0x7f907f8bb010|Src n/a
[engine] opt done String class>>new: |AST 51|Tier 2|Time 170( 153+17 )ms|Inlined 2Y 1N|IR 213/ 474|CodeSize 1885|Addr 0x7f907f8d6110|Src n/a
[engine] opt done SmallInteger>>printString |AST 152|Tier 2|Time 297( 274+23 )ms|Inlined 4Y 1N|IR 362/ 377|CodeSize 1188|Addr 0x7f907f8d8910|Src n/a
[engine] Truffle runtime statistics for engine 3
Compilations : 74
Success : 68
Temporary Bailouts : 1
org.graalvm.compiler.core.common.RetryableBailoutException: Assumption invalidated while compiling code: Object[Instance<org.graalvm.compiler.truffle.runtime.OptimizedAssumption>]: 1
Permanent Bailouts : 0
Failed : 0
Interrupted : 5
Invalidated : 1
Profiled Return Type : 1
Queues : 87
Dequeues : 17
Target inlined into only caller : 17
Splits : 235
Compilation Accuracy : 0.986486
Queue Accuracy : 0.804598
Compilation Utilization : 0.052294
Remaining Compilation Queue : 0
Time to queue : count= 87, sum= 826212, min= 89, average= 9496.70, max= 221013 (milliseconds), maxTarget=SmallInteger>>printString
Time waiting in queue : count= 74, sum= 21978, min= 0, average= 297.00, max= 1241 (milliseconds), maxTarget=DeviceTaskDataRecord>>pending:
Time for compilation : count= 69, sum= 11715, min= 22, average= 169.79, max= 2064 (milliseconds), maxTarget=Scheduler>>start
Truffle Tier : count= 68, sum= 9412, min= 19, average= 138.42, max= 1564 (milliseconds), maxTarget=Scheduler>>start
Graal Tier : count= 68, sum= 1224, min= 1, average= 18.01, max= 335 (milliseconds), maxTarget=Scheduler>>start
Code Installation : count= 68, sum= 748, min= 1, average= 11.01, max= 165 (milliseconds), maxTarget=Scheduler>>start
Truffle node count : count= 69, sum= 26405, min= 12, average= 382.68, max= 6055, maxTarget=Scheduler>>start
Trivial : count= 69, sum= 10158, min= 4, average= 147.22, max= 2255, maxTarget=Scheduler>>start
Non Trivial : count= 69, sum= 16247, min= 8, average= 235.46, max= 3800, maxTarget=Scheduler>>start
Monomorphic : count= 69, sum= 16147, min= 8, average= 234.01, max= 3777, maxTarget=Scheduler>>start
Polymorphic : count= 69, sum= 100, min= 0, average= 1.45, max= 23, maxTarget=Scheduler>>start
Megamorphic : count= 69, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=TaskState>>isWaitingWithPacket
Truffle call count : count= 69, sum= 342, min= 0, average= 4.96, max= 100, maxTarget=Scheduler>>start
Indirect : count= 69, sum= 3, min= 0, average= 0.04, max= 1, maxTarget=TaskControlBlock>>runTask
Direct : count= 69, sum= 339, min= 0, average= 4.91, max= 100, maxTarget=Scheduler>>start
Dispatched : count= 69, sum= 11, min= 0, average= 0.16, max= 7, maxTarget=Scheduler>>start
Inlined : count= 69, sum= 328, min= 0, average= 4.75, max= 93, maxTarget=Scheduler>>start
---------- :
Cloned : count= 69, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=TaskState>>isWaitingWithPacket
Not Cloned : count= 69, sum= 340, min= 0, average= 4.93, max= 100, maxTarget=Scheduler>>start
Truffle loops : count= 69, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=TaskState>>isWaitingWithPacket
Graal node count :
After Truffle Tier : count= 69, sum= 25905, min= 18, average= 375.43, max= 4856, maxTarget=Scheduler>>start
After Graal Tier : count= 69, sum= 52118, min= 20, average= 755.33, max= 10483, maxTarget=Scheduler>>start
Graal compilation result :
Code size : count= 68, sum= 184861, min= 154, average= 2718.54, max= 42189, maxTarget=Scheduler>>start
Total frame size : count= 68, sum= 5024, min= 32, average= 73.88, max= 416, maxTarget=Scheduler>>start
Exception handlers : count= 68, sum= 16, min= 0, average= 0.24, max= 7, maxTarget=Scheduler>>start
Infopoints : count= 68, sum= 1849, min= 4, average= 27.19, max= 335, maxTarget=Scheduler>>start
CALL : count= 68, sum= 1510, min= 4, average= 22.21, max= 269, maxTarget=Scheduler>>start
IMPLICIT_EXCEPTION : count= 68, sum= 317, min= 0, average= 4.66, max= 66, maxTarget=Scheduler>>start
SAFEPOINT : count= 68, sum= 22, min= 0, average= 0.32, max= 4, maxTarget=Scheduler>>createHandler:priority:work:state:
Marks : count= 68, sum= 448, min= 6, average= 6.59, max= 13, maxTarget=Scheduler>>start
Data references : count= 68, sum= 2002, min= 1, average= 29.44, max= 501, maxTarget=Scheduler>>start
Raw
b5e925dc-Sieve.trace.log
# Sieve (iterations: 250, problem size: 3000) on Mon Jul 26 08:15:23 UTC 2021
# `/home/fniephaus/bin/graalvm-ee-java11-21.2.0/bin/trufflesqueak --experimental-options --smalltalk.disable-startup --smalltalk.disable-interrupts --engine.Mode=default --engine.MultiTier=false --engine.DynamicCompilationThresholds=false --engine.TraceCompilation --engine.CompilationStatistics --log.file="/home/fniephaus/dev/are-we-fast-yet/traces/Sieve.trace.log" --quiet --code "FileStream startUp: true. Harness new run: #(nil 'Sieve' 250 3000)" /home/fniephaus/dev/are-we-fast-yet/benchmarks/Smalltalk/AWFY64-sista-fbc.image`
[engine] opt done Sieve>>sieve:size: |AST 134|Tier 2|Time 216( 185+31 )ms|Inlined 0Y 0N|IR 223/ 369|CodeSize 1618|Addr 0x7fc6237e5790|Src n/a
[engine] opt done Magnitude>>min: |AST 21|Tier 2|Time 43( 40+3 )ms|Inlined 0Y 0N|IR 53/ 34|CodeSize 206|Addr 0x7fc6237edc90|Src n/a
[engine] opt done SequenceableCollection>>from:to:put: |AST 162|Tier 2|Time 166( 145+21 )ms|Inlined 1Y 0N|IR 228/ 660|CodeSize 2826|Addr 0x7fc623805910|Src n/a
[engine] opt done Sieve>>verifyResult: |AST 15|Tier 2|Time 59( 57+2 )ms|Inlined 0Y 0N|IR 25/ 28|CodeSize 192|Addr 0x7fc62380f710|Src n/a
[engine] opt done SequenceableCollection>>atAllPut: |AST 48|Tier 2|Time 236( 217+19 )ms|Inlined 2Y 0N|IR 265/ 552|CodeSize 1928|Addr 0x7fc62381c590|Src n/a
[engine] opt done ArrayedCollection class>>new:withAll: |AST 31|Tier 2|Time 333( 301+32 )ms|Inlined 3Y 0N|IR 335/ 627|CodeSize 2433|Addr 0x7fc62381ec90|Src n/a
[engine] opt done Sieve>>benchmark |AST 44|Tier 2|Time 377( 332+45 )ms|Inlined 5Y 0N|IR 564/ 892|CodeSize 3153|Addr 0x7fc623825190|Src n/a
[engine] opt done Benchmark>>innerBenchmarkLoop: |AST 59|Tier 2|Time 281( 222+59 )ms|Inlined 7Y 0N|IR 657/ 967|CodeSize 3002|Addr 0x7fc62382a890|Src n/a
[engine] opt done String class>>new: |AST 51|Tier 2|Time 177( 158+19 )ms|Inlined 2Y 1N|IR 213/ 474|CodeSize 1885|Addr 0x7fc62383ce90|Src n/a
[engine] opt done SmallInteger>>printString |AST 152|Tier 2|Time 253( 230+23 )ms|Inlined 4Y 1N|IR 311/ 609|CodeSize 2090|Addr 0x7fc62383f490|Src n/a
[engine] Truffle runtime statistics for engine 3
Compilations : 11
Success : 10
Temporary Bailouts : 0
Permanent Bailouts : 0
Failed : 0
Interrupted : 1
Invalidated : 0
Queues : 11
Dequeues : 0
Splits : 225
Compilation Accuracy : 1.000000
Queue Accuracy : 1.000000
Compilation Utilization : 0.049792
Remaining Compilation Queue : 0
Time to queue : count= 11, sum= 94232, min= 119, average= 8566.62, max= 42839 (milliseconds), maxTarget=SmallInteger>>printString
Time waiting in queue : count= 11, sum= 119, min= 0, average= 10.83, max= 70 (milliseconds), maxTarget=SequenceableCollection>>atAllPut:
Time for compilation : count= 10, sum= 2170, min= 46, average= 217.01, max= 377 (milliseconds), maxTarget=Sieve>>benchmark
Truffle Tier : count= 10, sum= 1885, min= 39, average= 188.51, max= 331 (milliseconds), maxTarget=Sieve>>benchmark
Graal Tier : count= 10, sum= 185, min= 1, average= 18.59, max= 40 (milliseconds), maxTarget=Benchmark>>innerBenchmarkLoop:
Code Installation : count= 10, sum= 99, min= 2, average= 9.91, max= 20 (milliseconds), maxTarget=Sieve>>sieve:size:
Truffle node count : count= 10, sum= 3688, min= 23, average= 368.80, max= 812, maxTarget=Benchmark>>innerBenchmarkLoop:
Trivial : count= 10, sum= 1386, min= 8, average= 138.60, max= 298, maxTarget=Benchmark>>innerBenchmarkLoop:
Non Trivial : count= 10, sum= 2302, min= 15, average= 230.20, max= 514, maxTarget=Benchmark>>innerBenchmarkLoop:
Monomorphic : count= 10, sum= 2284, min= 15, average= 228.40, max= 514, maxTarget=Benchmark>>innerBenchmarkLoop:
Polymorphic : count= 10, sum= 18, min= 0, average= 1.80, max= 9, maxTarget=String class>>new:
Megamorphic : count= 10, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Sieve>>sieve:size:
Truffle call count : count= 10, sum= 26, min= 0, average= 2.60, max= 7, maxTarget=Benchmark>>innerBenchmarkLoop:
Indirect : count= 10, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Sieve>>sieve:size:
Direct : count= 10, sum= 26, min= 0, average= 2.60, max= 7, maxTarget=Benchmark>>innerBenchmarkLoop:
Dispatched : count= 10, sum= 2, min= 0, average= 0.20, max= 1, maxTarget=String class>>new:
Inlined : count= 10, sum= 24, min= 0, average= 2.40, max= 7, maxTarget=Benchmark>>innerBenchmarkLoop:
---------- :
Cloned : count= 10, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Sieve>>sieve:size:
Not Cloned : count= 10, sum= 26, min= 0, average= 2.60, max= 7, maxTarget=Benchmark>>innerBenchmarkLoop:
Truffle loops : count= 10, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Sieve>>sieve:size:
Graal node count :
After Truffle Tier : count= 10, sum= 2874, min= 25, average= 287.40, max= 657, maxTarget=Benchmark>>innerBenchmarkLoop:
After Graal Tier : count= 10, sum= 5212, min= 28, average= 521.20, max= 967, maxTarget=Benchmark>>innerBenchmarkLoop:
Graal compilation result :
Code size : count= 10, sum= 19333, min= 192, average= 1933.30, max= 3153, maxTarget=Sieve>>benchmark
Total frame size : count= 10, sum= 944, min= 32, average= 94.40, max= 192, maxTarget=Benchmark>>innerBenchmarkLoop:
Exception handlers : count= 10, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=Sieve>>sieve:size:
Infopoints : count= 10, sum= 177, min= 4, average= 17.70, max= 30, maxTarget=Benchmark>>innerBenchmarkLoop:
CALL : count= 10, sum= 139, min= 4, average= 13.90, max= 22, maxTarget=Benchmark>>innerBenchmarkLoop:
IMPLICIT_EXCEPTION : count= 10, sum= 23, min= 0, average= 2.30, max= 5, maxTarget=String class>>new:
SAFEPOINT : count= 10, sum= 15, min= 0, average= 1.50, max= 4, maxTarget=Sieve>>benchmark
Marks : count= 10, sum= 77, min= 6, average= 7.70, max= 10, maxTarget=Sieve>>benchmark
Data references : count= 10, sum= 125, min= 1, average= 12.50, max= 27, maxTarget=String class>>new:
Raw
b5e925dc-Storage.trace.log
# Storage (iterations: 250, problem size: 1000) on Mon Jul 26 08:12:06 UTC 2021
# `/home/fniephaus/bin/graalvm-ee-java11-21.2.0/bin/trufflesqueak --experimental-options --smalltalk.disable-startup --smalltalk.disable-interrupts --engine.Mode=default --engine.MultiTier=false --engine.DynamicCompilationThresholds=false --engine.TraceCompilation --engine.CompilationStatistics --log.file="/home/fniephaus/dev/are-we-fast-yet/traces/Storage.trace.log" --quiet --code "FileStream startUp: true. Harness new run: #(nil 'Storage' 250 1000)" /home/fniephaus/dev/are-we-fast-yet/benchmarks/Smalltalk/AWFY64-sista-fbc.image`
[engine] opt done SomRandom>>next |AST 39|Tier 2|Time 116( 94+22 )ms|Inlined 0Y 0N|IR 83/ 160|CodeSize 510|Addr 0x7f56f77bcd10|Src n/a
[engine] opt done Storage>>buildTreeDepth:with: |AST 154|Tier 2|Time 1929( 388+1541)ms|Inlined 5Y 1N|IR 1059/17084|CodeSize 79135|Addr 0x7f56f77f5610|Src n/a
[engine] opt done SomRandom>>initialize |AST 10|Tier 2|Time 34( 29+4 )ms|Inlined 0Y 0N|IR 68/ 79|CodeSize 238|Addr 0x7f56f783a690|Src n/a
[engine] opt done SomRandom class>>new |AST 26|Tier 2|Time 107( 103+4 )ms|Inlined 3Y 0N|IR 34/ 100|CodeSize 422|Addr 0x7f56f783dd90|Src n/a
[engine] opt done Storage>>verifyResult: |AST 15|Tier 2|Time 31( 29+2 )ms|Inlined 0Y 0N|IR 25/ 28|CodeSize 192|Addr 0x7f56f783e610|Src n/a
[engine] opt done Storage>>benchmark |AST 49|Tier 2|Time 1862( 409+1453)ms|Inlined 10Y 1N|IR 801/11344|CodeSize 37521|Addr 0x7f56f7844f10|Src n/a
[engine] opt done Benchmark>>innerBenchmarkLoop: |AST 59|Tier 2|Time 2244( 348+1896)ms|Inlined 12Y 1N|IR 898/14319|CodeSize 51997|Addr 0x7f56f7877c10|Src n/a
[engine] opt done String class>>new: |AST 51|Tier 2|Time 197( 178+19 )ms|Inlined 2Y 1N|IR 213/ 474|CodeSize 1885|Addr 0x7f56f78bf890|Src n/a
[engine] opt done SmallInteger>>printString |AST 152|Tier 2|Time 278( 258+20 )ms|Inlined 4Y 1N|IR 362/ 377|CodeSize 1185|Addr 0x7f56f78c1d10|Src n/a
[engine] Truffle runtime statistics for engine 3
Compilations : 11
Success : 9
Temporary Bailouts : 0
Permanent Bailouts : 0
Failed : 0
Interrupted : 2
Invalidated : 0
Queues : 11
Dequeues : 1
Target inlined into only caller : 1
Splits : 226
Compilation Accuracy : 1.000000
Queue Accuracy : 0.909091
Compilation Utilization : 0.137581
Remaining Compilation Queue : 0
Time to queue : count= 11, sum= 114825, min= 37, average= 10438.69, max= 47206 (milliseconds), maxTarget=SmallInteger>>printString
Time waiting in queue : count= 11, sum= 183, min= 1, average= 16.68, max= 112 (milliseconds), maxTarget=Storage>>verifyResult:
Time for compilation : count= 9, sum= 6828, min= 32, average= 758.77, max= 2245 (milliseconds), maxTarget=Benchmark>>innerBenchmarkLoop:
Truffle Tier : count= 9, sum= 1832, min= 28, average= 203.59, max= 407 (milliseconds), maxTarget=Storage>>benchmark
Graal Tier : count= 9, sum= 3117, min= 1, average= 346.37, max= 1263 (milliseconds), maxTarget=Benchmark>>innerBenchmarkLoop:
Code Installation : count= 9, sum= 1879, min= 1, average= 208.82, max= 651 (milliseconds), maxTarget=Storage>>buildTreeDepth:with:
Truffle node count : count= 9, sum= 4433, min= 18, average= 492.56, max= 1260, maxTarget=Benchmark>>innerBenchmarkLoop:
Trivial : count= 9, sum= 1723, min= 8, average= 191.44, max= 484, maxTarget=Benchmark>>innerBenchmarkLoop:
Non Trivial : count= 9, sum= 2710, min= 10, average= 301.11, max= 776, maxTarget=Benchmark>>innerBenchmarkLoop:
Monomorphic : count= 9, sum= 2674, min= 10, average= 297.11, max= 770, maxTarget=Benchmark>>innerBenchmarkLoop:
Polymorphic : count= 9, sum= 36, min= 0, average= 4.00, max= 9, maxTarget=String class>>new:
Megamorphic : count= 9, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=SomRandom>>next
Truffle call count : count= 9, sum= 41, min= 0, average= 4.56, max= 13, maxTarget=Benchmark>>innerBenchmarkLoop:
Indirect : count= 9, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=SomRandom>>next
Direct : count= 9, sum= 41, min= 0, average= 4.56, max= 13, maxTarget=Benchmark>>innerBenchmarkLoop:
Dispatched : count= 9, sum= 5, min= 0, average= 0.56, max= 1, maxTarget=Storage>>buildTreeDepth:with:
Inlined : count= 9, sum= 36, min= 0, average= 4.00, max= 12, maxTarget=Benchmark>>innerBenchmarkLoop:
---------- :
Cloned : count= 9, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=SomRandom>>next
Not Cloned : count= 9, sum= 41, min= 0, average= 4.56, max= 13, maxTarget=Benchmark>>innerBenchmarkLoop:
Truffle loops : count= 9, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=SomRandom>>next
Graal node count :
After Truffle Tier : count= 9, sum= 3543, min= 25, average= 393.67, max= 1059, maxTarget=Storage>>buildTreeDepth:with:
After Graal Tier : count= 9, sum= 43965, min= 28, average= 4885.00, max= 17084, maxTarget=Storage>>buildTreeDepth:with:
Graal compilation result :
Code size : count= 9, sum= 173085, min= 192, average= 19231.67, max= 79135, maxTarget=Storage>>buildTreeDepth:with:
Total frame size : count= 9, sum= 3856, min= 32, average= 428.44, max= 1328, maxTarget=Storage>>buildTreeDepth:with:
Exception handlers : count= 9, sum= 176, min= 0, average= 19.56, max= 64, maxTarget=Storage>>benchmark
Infopoints : count= 9, sum= 1846, min= 4, average= 205.11, max= 791, maxTarget=Storage>>buildTreeDepth:with:
CALL : count= 9, sum= 1471, min= 4, average= 163.44, max= 611, maxTarget=Storage>>buildTreeDepth:with:
IMPLICIT_EXCEPTION : count= 9, sum= 375, min= 0, average= 41.67, max= 180, maxTarget=Storage>>buildTreeDepth:with:
Marks : count= 9, sum= 231, min= 6, average= 25.67, max= 70, maxTarget=Storage>>benchmark
Data references : count= 9, sum= 1902, min= 2, average= 211.33, max= 653, maxTarget=Storage>>buildTreeDepth:with:
Raw
b5e925dc-Towers.trace.log
# Towers (iterations: 250, problem size: 600) on Mon Jul 26 08:13:01 UTC 2021
# `/home/fniephaus/bin/graalvm-ee-java11-21.2.0/bin/trufflesqueak --experimental-options --smalltalk.disable-startup --smalltalk.disable-interrupts --engine.Mode=default --engine.MultiTier=false --engine.DynamicCompilationThresholds=false --engine.TraceCompilation --engine.CompilationStatistics --log.file="/home/fniephaus/dev/are-we-fast-yet/traces/Towers.trace.log" --quiet --code "FileStream startUp: true. Harness new run: #(nil 'Towers' 250 600)" /home/fniephaus/dev/are-we-fast-yet/benchmarks/Smalltalk/AWFY64-sista-fbc.image`
[engine] opt done TowersDisk>>next: |AST 11|Tier 2|Time 113( 92+21 )ms|Inlined 0Y 0N|IR 61/ 184|CodeSize 459|Addr 0x7f799af7e690|Src n/a
[engine] opt done Towers>>popDiskFrom: |AST 83|Tier 2|Time 146( 137+10 )ms|Inlined 1Y 0N|IR 177/ 360|CodeSize 962|Addr 0x7f799af9d710|Src n/a
[engine] opt done Towers>>pushDisk:onPile: |AST 99|Tier 2|Time 266( 230+36 )ms|Inlined 1Y 0N|IR 360/ 1133|CodeSize 3946|Addr 0x7f799af9f410|Src n/a
[engine] opt done Towers>>moveTopDiskFrom:to: |AST 49|Tier 2|Time 201( 144+57 )ms|Inlined 4Y 0N|IR 617/ 1669|CodeSize 5485|Addr 0x7f799afc2090|Src n/a
[engine] opt done TowersDisk class>>new: |AST 29|Tier 2|Time 96( 92+4 )ms|Inlined 2Y 0N|IR 37/ 104|CodeSize 424|Addr 0x7f799afcf890|Src n/a
[engine] opt done Towers>>buildTowerAt:disks: |AST 67|Tier 2|Time 233( 156+78 )ms|Inlined 5Y 0N|IR 463/ 1801|CodeSize 8248|Addr 0x7f799afd2010|Src n/a
[engine] opt done Towers>>move:disksFrom:to: |AST 128|Tier 2|Time 2116(1455+660 )ms|Inlined 43Y 5N|IR 4763/12046|CodeSize 44620|Addr 0x7f799afd8a90|Src n/a
[engine] opt done Towers>>verifyResult: |AST 15|Tier 2|Time 41( 39+2 )ms|Inlined 0Y 0N|IR 25/ 28|CodeSize 192|Addr 0x7f799afffc10|Src n/a
[engine] opt done Towers>>benchmark |AST 65|Tier 2|Time 2085(1457+628 )ms|Inlined 50Y 5N|IR 2950/ 7026|CodeSize 21700|Addr 0x7f799b006590|Src n/a
[engine] opt done Benchmark>>innerBenchmarkLoop: |AST 59|Tier 2|Time 3483(1514+1970)ms|Inlined 85Y 8N|IR 4776/17116|CodeSize 55086|Addr 0x7f799b021c10|Src n/a
[engine] opt done String class>>new: |AST 51|Tier 2|Time 177( 158+19 )ms|Inlined 2Y 1N|IR 213/ 474|CodeSize 1885|Addr 0x7f799b069d90|Src n/a
[engine] opt done SmallInteger>>printString |AST 152|Tier 2|Time 259( 240+19 )ms|Inlined 4Y 1N|IR 362/ 377|CodeSize 1185|Addr 0x7f799b06bf90|Src n/a
[engine] Truffle runtime statistics for engine 3
Compilations : 13
Success : 12
Temporary Bailouts : 0
Permanent Bailouts : 0
Failed : 0
Interrupted : 1
Invalidated : 0
Queues : 15
Dequeues : 2
Target inlined into only caller : 2
Splits : 226
Compilation Accuracy : 1.000000
Queue Accuracy : 0.866667
Compilation Utilization : 0.127436
Remaining Compilation Queue : 0
Time to queue : count= 15, sum= 162585, min= 42, average= 10839.00, max= 69992 (milliseconds), maxTarget=SmallInteger>>printString
Time waiting in queue : count= 13, sum= 383, min= 0, average= 29.46, max= 246 (milliseconds), maxTarget=Towers>>moveTopDiskFrom:to:
Time for compilation : count= 12, sum= 9249, min= 42, average= 770.78, max= 3485 (milliseconds), maxTarget=Benchmark>>innerBenchmarkLoop:
Truffle Tier : count= 12, sum= 5711, min= 38, average= 475.98, max= 1513 (milliseconds), maxTarget=Benchmark>>innerBenchmarkLoop:
Graal Tier : count= 12, sum= 2173, min= 1, average= 181.16, max= 1321 (milliseconds), maxTarget=Benchmark>>innerBenchmarkLoop:
Code Installation : count= 12, sum= 1363, min= 1, average= 113.63, max= 650 (milliseconds), maxTarget=Benchmark>>innerBenchmarkLoop:
Truffle node count : count= 12, sum= 18362, min= 19, average= 1530.17, max= 7679, maxTarget=Benchmark>>innerBenchmarkLoop:
Trivial : count= 12, sum= 6855, min= 8, average= 571.25, max= 2859, maxTarget=Benchmark>>innerBenchmarkLoop:
Non Trivial : count= 12, sum= 11507, min= 11, average= 958.92, max= 4820, maxTarget=Benchmark>>innerBenchmarkLoop:
Monomorphic : count= 12, sum= 11244, min= 11, average= 937.00, max= 4715, maxTarget=Benchmark>>innerBenchmarkLoop:
Polymorphic : count= 12, sum= 263, min= 0, average= 21.92, max= 105, maxTarget=Benchmark>>innerBenchmarkLoop:
Megamorphic : count= 12, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=TowersDisk>>next:
Truffle call count : count= 12, sum= 217, min= 0, average= 18.08, max= 93, maxTarget=Benchmark>>innerBenchmarkLoop:
Indirect : count= 12, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=TowersDisk>>next:
Direct : count= 12, sum= 217, min= 0, average= 18.08, max= 93, maxTarget=Benchmark>>innerBenchmarkLoop:
Dispatched : count= 12, sum= 20, min= 0, average= 1.67, max= 8, maxTarget=Benchmark>>innerBenchmarkLoop:
Inlined : count= 12, sum= 197, min= 0, average= 16.42, max= 85, maxTarget=Benchmark>>innerBenchmarkLoop:
---------- :
Cloned : count= 12, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=TowersDisk>>next:
Not Cloned : count= 12, sum= 217, min= 0, average= 18.08, max= 93, maxTarget=Benchmark>>innerBenchmarkLoop:
Truffle loops : count= 12, sum= 0, min= 0, average= 0.00, max= 0, maxTarget=TowersDisk>>next:
Graal node count :
After Truffle Tier : count= 12, sum= 14804, min= 25, average= 1233.67, max= 4776, maxTarget=Benchmark>>innerBenchmarkLoop:
After Graal Tier : count= 12, sum= 42318, min= 28, average= 3526.50, max= 17116, maxTarget=Benchmark>>innerBenchmarkLoop:
Graal compilation result :
Code size : count= 12, sum= 144192, min= 192, average= 12016.00, max= 55086, maxTarget=Benchmark>>innerBenchmarkLoop:
Total frame size : count= 12, sum= 1760, min= 32, average= 146.67, max= 448, maxTarget=Towers>>move:disksFrom:to:
Exception handlers : count= 12, sum= 25, min= 0, average= 2.08, max= 15, maxTarget=Benchmark>>innerBenchmarkLoop:
Infopoints : count= 12, sum= 1606, min= 4, average= 133.83, max= 644, maxTarget=Benchmark>>innerBenchmarkLoop:
CALL : count= 12, sum= 1290, min= 4, average= 107.50, max= 506, maxTarget=Benchmark>>innerBenchmarkLoop:
IMPLICIT_EXCEPTION : count= 12, sum= 312, min= 0, average= 26.00, max= 138, maxTarget=Benchmark>>innerBenchmarkLoop:
SAFEPOINT : count= 12, sum= 4, min= 0, average= 0.33, max= 4, maxTarget=Towers>>buildTowerAt:disks:
Marks : count= 12, sum= 101, min= 6, average= 8.42, max= 21, maxTarget=Benchmark>>innerBenchmarkLoop:
Data references : count= 12, sum= 1224, min= 3, average= 102.00, max= 517, maxTarget=Benchmark>>innerBenchmarkLoop:
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment