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 36985024 instr in 10872.09 secs (3401.832/sec)

 10 time consuming outward calls from interpreter (129128 total)		[ms, #, gfi, name]
   29107	   37122	  44	AllocatorImpl.NewSystemObject
   27019	   36521	  65	RCMapWalkerImpl.AssignComposite
    6950	    9753	  91	IOCommonImpl.GetChar
    6316	    9405	  12	InstructionsImpl.DMultiply
    6165	    9074	  68	AtomImpl.GetPropFromList
    5040	    7989	  12	InstructionsImpl.SignDivide
    4108	    3605	  96	IOPrintImpl.PutRope
    2262	    2851	  73	RopeImpl.FromChar
    2107	    2911	  12	InstructionsImpl.DDiv
    2089	    2962	 226	RefTabImpl.Fetch
~~~~~~~~~~~~~~~~~~~~~~~~~~~

Stats for Instruction cache 0 (associative, lines: 100, quads/line: 2, words/quad: 4)
  probes: 27078591  (read: 27078591, write: 0)
  write misses: 0, writes to clean quads: 0
  misses: 874693 (380370 (43.5%) existing line)
  misses caused by jumps: 310714 (1.15% of probes)
  miss rate: 3.23%
  map misses: 35715 (0.132%)
  dirty writes: 0
  line occupancy:
    1 quad(s)/line: 56.5
    2 quad(s)/line: 43.5

Stats for Data cache 0 (associative, lines: 100, quads/line: 2, words/quad: 4)
  probes: 21032378  (read: 14637948, write: 6394430)
  write misses: 195663, writes to clean quads: 345168
  misses: 731059 (226384 (31.0%) existing line)
  miss rate: 3.48%
  map misses: 188976 (0.899%)
  dirty writes: 345111
  line occupancy:
    1 quad(s)/line: 69.0
    2 quad(s)/line: 31.0

Stats for Real cache (directMap, lines: 512, quads/line: 8, words/quad: 4)
  probes: 1950863  (read: 1605752, write: 345111)
  write misses: 52740, writes to clean quads: 92621
  misses: 416630 (269257 (64.6%) existing line)
  miss rate: 21.4%
  map misses: 147373 (7.55%)
  dirty writes: 92047

Stats for Map cache (associative, lines: 256, quads/line: 4, words/quad: 1)
  probes: 224691  (read: 224691, write: 0)
  write misses: 0, writes to clean quads: 0
  misses: 3594 (1975 (55.0%) existing line)
  miss rate: 1.6%
  map misses: 117 (5.21e-2%)
  dirty writes: 0
  line occupancy:
    1 quad(s)/line: 45.0
    2 quad(s)/line: 27.7
    3 quad(s)/line: 16.8
    4 quad(s)/line: 10.5