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 36991624 instr in 10931.27 secs (3384.02/sec)

 10 time consuming outward calls from interpreter (129353 total)		[ms, #, gfi, name]
   29236	   37347	  44	AllocatorImpl.NewSystemObject
   26319	   36521	  65	RCMapWalkerImpl.AssignComposite
    6721	    9753	  91	IOCommonImpl.GetChar
    6107	    9405	  12	InstructionsImpl.DMultiply
    6001	    9074	  68	AtomImpl.GetPropFromList
    4933	    7989	  12	InstructionsImpl.SignDivide
    3697	    3605	  96	IOPrintImpl.PutRope
    2312	    2851	  73	RopeImpl.FromChar
    2209	    2962	 226	RefTabImpl.Fetch
    1870	    2911	  12	InstructionsImpl.DDiv
~~~~~~~~~~~~~~~~~~~~~~~~~~~

Stats for Instruction cache 0 (associative, lines: 50, quads/line: 4, words/quad: 4)
  probes: 27083391  (read: 27083391, write: 0)
  write misses: 0, writes to clean quads: 0
  misses: 1080749 (731178 (67.7%) existing line)
  misses caused by jumps: 391692 (1.45% of probes)
  miss rate: 3.99%
  map misses: 50421 (0.186%)
  dirty writes: 0
  line occupancy:
    1 quad(s)/line: 32.3
    2 quad(s)/line: 28.3
    3 quad(s)/line: 22.9
    4 quad(s)/line: 16.5

Stats for Data cache 0 (associative, lines: 50, quads/line: 4, words/quad: 4)
  probes: 21036053  (read: 14640498, write: 6395555)
  write misses: 362894, writes to clean quads: 632142
  misses: 1474158 (608276 (41.3%) existing line)
  miss rate: 7.01%
  map misses: 455638 (2.17%)
  dirty writes: 632120
  line occupancy:
    1 quad(s)/line: 58.7
    2 quad(s)/line: 29.0
    3 quad(s)/line: 8.64
    4 quad(s)/line: 3.64

Stats for Real cache (directMap, lines: 512, quads/line: 8, words/quad: 4)
  probes: 3187027  (read: 2554907, write: 632120)
  write misses: 61170, writes to clean quads: 86303
  misses: 536635 (338979 (63.2%) existing line)
  miss rate: 16.8%
  map misses: 197656 (6.2%)
  dirty writes: 85830

Stats for Map cache (associative, lines: 256, quads/line: 4, words/quad: 1)
  probes: 506059  (read: 506059, write: 0)
  write misses: 0, writes to clean quads: 0
  misses: 3843 (2119 (55.1%) existing line)
  miss rate: 0.759%
  map misses: 135 (2.67e-2%)
  dirty writes: 0
  line occupancy:
    1 quad(s)/line: 44.9
    2 quad(s)/line: 27.3
    3 quad(s)/line: 17.2
    4 quad(s)/line: 10.6