MarkGFI: ProcessPropsImpl gfi's are marked (77-77)
MarkGFI: InterpreterPrivateImpl gfi's are marked (302-302)
MarkGFI: EvaluateImpl gfi's are marked (299-300)
MarkGFI: AMEventsImpl gfi's are marked (287-288)
MarkGFI: SpinifexStarter gfi's are marked (937-937)
MarkGFI: InterpreterTool gfi's are marked (581-586)
MarkGFI: InterpreterImpl gfi's are marked (296-296)
MarkGFI: ChipNDalePackage gfi's are marked (805-896)
MarkGFI: CDCMosPackage gfi's are marked (897-908)
MarkGFI: CDValueImpl gfi's are marked (810-810)
MarkGFI: CDPropertiesImpl gfi's are marked (807-808)
MarkGFI: CDCellsImpl gfi's are marked (829-830)
MarkGFI: CDErrorsImpl gfi's are marked (877-877)
MarkGFI: CDOrientImpl gfi's are marked (817-817)
MarkGFI: CornerStitchingImpl gfi's are marked (917-917)
MarkGFI: CDObjectProcsImpl gfi's are marked (815-815)
MarkGFI: CDImpl gfi's are marked (805-805)
MarkGFI: CDInstancesImpl gfi's are marked (816-816)
MarkGFI: CDPinObjectsImpl gfi's are marked (876-876)
MarkGFI: TerminalIOImpl gfi's are marked (803-803)
MarkGFI: SXPackage gfi's are marked (918-933)
MarkGFI: SXCMosPackage gfi's are marked (934-936)
MarkGFI: SXAccessInternalImpl gfi's are marked (922-922)
MarkGFI: SXImpl gfi's are marked (923-923)
MarkGFI: SXLayersImpl gfi's are marked (929-929)
MarkGFI: SXAccessImpl gfi's are marked (921-921)
Initializing to run command tool
DragomanStartSpinifex
Executed 36978499 instr in 11384.3 secs (3248.202/sec)

 10 time consuming outward calls from interpreter (128907 total)		[ms, #, gfi, name]
   30130	   36901	  44	AllocatorImpl.NewSystemObject
   26932	   36521	  65	RCMapWalkerImpl.AssignComposite
    6566	    9753	  91	IOCommonImpl.GetChar
    6252	    9074	  68	AtomImpl.GetPropFromList
    6139	    9405	  12	InstructionsImpl.DMultiply
    5158	    7989	  12	InstructionsImpl.SignDivide
    3926	    3605	  96	IOPrintImpl.PutRope
    2267	    2851	  73	RopeImpl.FromChar
    2028	    2962	 226	RefTabImpl.Fetch
    1988	    2911	  12	InstructionsImpl.DDiv
~~~~~~~~~~~~~~~~~~~~~~~~~~~

Stats for Instruction cache 0 (associative, lines: 50, quads/line: 2, words/quad: 4)
  probes: 27073849  (read: 27073849, write: 0)
  write misses: 0, writes to clean quads: 0
  misses: 2286563 (942614 (41.2%) existing line)
  misses caused by jumps: 889275 (3.28% of probes)
  miss rate: 8.45%
  map misses: 138390 (0.511%)
  dirty writes: 0
  line occupancy:
    1 quad(s)/line: 58.8
    2 quad(s)/line: 41.2

Stats for Data cache 0 (associative, lines: 50, quads/line: 2, words/quad: 4)
  probes: 21028876  (read: 14635501, write: 6393375)
  write misses: 424171, writes to clean quads: 753741
  misses: 1795123 (492374 (27.4%) existing line)
  miss rate: 8.54%
  map misses: 568073 (2.7%)
  dirty writes: 753722
  line occupancy:
    1 quad(s)/line: 72.6
    2 quad(s)/line: 27.4

Stats for Real cache (directMap, lines: 512, quads/line: 8, words/quad: 4)
  probes: 4835408  (read: 4081686, write: 753722)
  write misses: 76085, writes to clean quads: 131978
  misses: 668462 (418395 (62.6%) existing line)
  miss rate: 13.8%
  map misses: 250067 (5.17%)
  dirty writes: 131366

Stats for Map cache (associative, lines: 256, quads/line: 4, words/quad: 1)
  probes: 706463  (read: 706463, write: 0)
  write misses: 0, writes to clean quads: 0
  misses: 3448 (1900 (55.1%) existing line)
  miss rate: 0.488%
  map misses: 107 (1.51e-2%)
  dirty writes: 0
  line occupancy:
    1 quad(s)/line: 44.9
    2 quad(s)/line: 27.1
    3 quad(s)/line: 17.6
    4 quad(s)/line: 10.4