CM_profile_run_proper_2.txt

Main__:
  at $00003c (line 0); called 1 times
  0.000009 sec (1 ops) spent executing
  201.855686 sec (455056013 ops) including child calls
Main:
  at $00958a (line 9386); called 1 times
  0.000073 sec (530 ops) spent executing
  201.855677 sec (455056012 ops) including child calls
FollowRulebook:
  at $0ec377 (line 205803); called 46628 times
  0.126276 sec (1310089 ops) spent executing
  201.855603 sec (455055479 ops) including child calls
B1_turn_sequence:
  at $0b10db (line 166939); called 53 times
  0.000605 sec (4318 ops) spent executing
  193.769143 sec (410411799 ops) including child calls
PARSE_COMMAND_R:
  at $009720 (line 9504); called 53 times
  0.000088 sec (265 ops) spent executing
  163.031087 sec (113037746 ops) including child calls
Parser__parse:
  at $005338 (line 6121); called 53 times
  0.005637 sec (90007 ops) spent executing
  163.030970 sec (113037216 ops) including child calls
Keyboard:
  at $0050b4 (line 5997); called 55 times
  0.001216 sec (18810 ops) spent executing
  151.569207 sec (470097 ops) including child calls
glk_select:
  at $000816 (line 1655); called 58 times
  0.000162 sec (174 ops) spent executing
  144.386401 sec (174 ops) including child calls
<@glk_select>:
  at $f00000c0 (line 0); called 58 times
  144.386239 sec (0 ops) spent executing
  144.386239 sec (0 ops) including child calls
KeyboardPrimitive:
  at $0050a3 (line 5985); called 57 times
  0.000063 sec (114 ops) spent executing
  142.547936 sec (123317 ops) including child calls
VM_ReadKeyboard:
  at $0013c7 (line 2385); called 57 times
  0.000611 sec (1539 ops) spent executing
  142.547873 sec (123203 ops) including child calls
ProcessActivityRulebook:
  at $0e6ec2 (line 202763); called 40644 times
  0.037956 sec (280881 ops) spent executing
  24.527444 sec (267396276 ops) including child calls
BeginAction:
  at $0e62e3 (line 202238); called 88 times
  0.000257 sec (1812 ops) spent executing
  19.937050 sec (188404869 ops) including child calls
ActionPrimitive:
  at $0e6350 (line 202257); called 88 times
  0.000582 sec (2761 ops) spent executing
  19.932581 sec (188362135 ops) including child calls
ForActivity:
  at $0e6f8f (line 202806); called 13548 times
  0.013344 sec (100314 ops) spent executing
  18.840518 sec (232660109 ops) including child calls
B9_action_processing:
  at $0b2da9 (line 168043); called 86 times
  0.000533 sec (4390 ops) spent executing
  18.762401 sec (187348575 ops) including child calls
CarryOutActivity:
  at $0e6ee8 (line 202777); called 11607 times
  0.015627 sec (46428 ops) spent executing
  18.514499 sec (226625916 ops) including child calls
GENERATE_ACTION_R:
  at $00978e (line 9533); called 53 times
  0.000172 sec (1516 ops) spent executing
  18.295474 sec (175959512 ops) including child calls
DESCEND_TO_SPECIFIC_ACTION_R:
  at $0022d3 (line 3145); called 72 times
  0.000076 sec (287 ops) spent executing
  15.566572 sec (141749296 ops) including child calls
GenericVerbSub:
  at $0e69bf (line 202580); called 99 times
  0.000193 sec (1292 ops) spent executing
  15.566436 sec (141748926 ops) including child calls
B11_specific_action_processi:
  at $0b39d6 (line 168511); called 99 times
  0.000484 sec (3790 ops) spent executing
  15.565193 sec (141739881 ops) including child calls
R_28:
  at $01c764 (line 86636); called 93 times
  0.000140 sec (557 ops) spent executing
  13.993547 sec (128337751 ops) including child calls
R_31:
  at $01c84a (line 86674); called 90 times
  0.000229 sec (1527 ops) spent executing
  13.002971 sec (129427003 ops) including child calls
WhetherProvides:
  at $0ebf0c (line 205638); called 1688031 times
  5.683011 sec (51430320 ops) spent executing
  12.844671 sec (132287790 ops) including child calls
GoSub:
  at $0136b0 (line 81467); called 27 times
  0.000041 sec (54 ops) spent executing
  12.157561 sec (122604476 ops) including child calls
B154_report_going:
  at $0ca256 (line 182566); called 27 times
  0.000083 sec (810 ops) spent executing
  12.052293 sec (121692149 ops) including child calls
R_161:
  at $05a3e6 (line 116314); called 27 times
  0.000042 sec (243 ops) spent executing
  12.052124 sec (121690880 ops) including child calls
KERNEL_104:
  at $05a410 (line 116325); called 27 times
  0.000044 sec (243 ops) spent executing
  12.052066 sec (121690502 ops) including child calls
LookAfterGoing:
  at $0e6227 (line 202205); called 27 times
  0.000031 sec (81 ops) spent executing
  12.052022 sec (121690259 ops) including child calls
AbbreviatedRoomDescription:
  at $0e623b (line 202215); called 27 times
  0.000119 sec (648 ops) spent executing
  12.051933 sec (121689964 ops) including child calls
LookSub:
  at $013700 (line 81479); called 27 times
  0.000022 sec (54 ops) spent executing
  12.050619 sec (121677976 ops) including child calls
B165_carry_out_looking:
  at $0cad4e (line 183041); called 27 times
  0.000263 sec (1757 ops) spent executing
  12.047554 sec (121654609 ops) including child calls
SearchScope:
  at $008778 (line 8705); called 1746 times
  0.005912 sec (38455 ops) spent executing
  12.017197 sec (169555155 ops) including child calls
VM_Save_Undo:
  at $001f83 (line 2988); called 55 times
  11.953035 sec (275 ops) spent executing
  11.953035 sec (275 ops) including child calls
DoScopeActionAndRecurse:
  at $0088e7 (line 8771); called 30434 times
  0.093488 sec (522154 ops) spent executing
  11.703953 sec (167296184 ops) including child calls
R_1379:
  at $05d00a (line 117901); called 27 times
  0.000043 sec (162 ops) spent executing
  11.375746 sec (115784946 ops) including child calls
B530_description_priority:
  at $0d3058 (line 189409); called 27 times
  0.000128 sec (810 ops) spent executing
  11.375557 sec (115783812 ops) including child calls
TestConcealment:
  at $0fe9aa (line 212108); called 19765 times
  0.019024 sec (102450 ops) spent executing
  11.257944 sec (163718492 ops) including child calls
B84_for_deciding_the_conceal:
  at $0c6efe (line 180316); called 8631 times
  0.036827 sec (319777 ops) spent executing
  11.038008 sec (161830054 ops) including child calls
TestScope:
  at $0086b1 (line 8679); called 1514 times
  0.003600 sec (28766 ops) spent executing
  10.309505 sec (148352549 ops) including child calls
TestVisibility:
  at $0fe8c9 (line 212082); called 1384 times
  0.001949 sec (9133 ops) spent executing
  9.050547 sec (124915915 ops) including child calls
GetEitherOrProperty:
  at $0ec05e (line 205693); called 978647 times
  1.351879 sec (12972799 ops) spent executing
  8.937218 sec (87549860 ops) including child calls
R_1382:
  at $0760ba (line 132507); called 45 times
  0.000036 sec (180 ops) spent executing
  8.762501 sec (110602419 ops) including child calls
B531_description_concealing:
  at $0d319e (line 189463); called 45 times
  0.001101 sec (8325 ops) spent executing
  8.762224 sec (110600619 ops) including child calls
ScopeWithin:
  at $0088a5 (line 8752); called 3469 times
  0.019576 sec (162996 ops) spent executing
  8.693590 sec (123363336 ops) including child calls
R_15:
  at $014e01 (line 82319); called 50 times
  0.000049 sec (200 ops) spent executing
  8.259076 sec (84545384 ops) including child calls
B8_every_turn:
  at $0b1f7b (line 167517); called 50 times
  0.002337 sec (16251 ops) spent executing
  8.258758 sec (84543384 ops) including child calls
B0_startup:
  at $0b0e7d (line 166845); called 1 times
  0.000009 sec (56 ops) spent executing
  8.086185 sec (44641786 ops) including child calls
R_11:
  at $014cba (line 82213); called 1 times
  0.000001 sec (4 ops) spent executing
  8.038783 sec (44128001 ops) including child calls
B4_when_play_begins:
  at $0b1589 (line 167131); called 1 times
  0.000054 sec (221 ops) spent executing
  8.038775 sec (44127961 ops) including child calls