Total time: 29.23500 s Total steps: 22141607 Mean step time: 1.320365 us __Step-Drop (0000) -> 996123 (4.50 %, += 4.50 %) rel step time 0.45 Apply (2191) -> 694393 (3.14 %, += 7.64 %) rel step time 0.48 Apply (8023) -> 566554 (2.56 %, += 10.19 %) rel step time 0.52 Apply (4746) -> 559874 (2.53 %, += 12.72 %) rel step time 0.66 Type (0000) -> 506810 (2.29 %, += 15.01 %) rel step time 0.35 Add-Digits (0871) -> 504984 (2.28 %, += 17.29 %) rel step time 0.37 Add (0000) -> 503861 (2.28 %, += 19.57 %) rel step time 0.77 Divmod-Digits (0871) -> 486025 (2.20 %, += 21.76 %) rel step time 0.36 Divmod (0000) -> 485993 (2.19 %, += 23.96 %) rel step time 0.94 Apply (9549) -> 485517 (2.19 %, += 26.15 %) rel step time 0.68 DoMapAccum (8023) -> 398555 (1.80 %, += 27.95 %) rel step time 1.46 DoMapAccum-Aux (8023) -> 390248 (1.76 %, += 29.71 %) rel step time 0.97 Map (4746) -> 367682 (1.66 %, += 31.37 %) rel step time 1.19 PutCommand (4746) -> 269089 (1.22 %, += 32.59 %) rel step time 1.02 IsIdentTail (8317) -> 265838 (1.20 %, += 33.79 %) rel step time 0.67 IsIdentTail$3:1 (8317) -> 262619 (1.19 %, += 34.98 %) rel step time 0.72 DoMapAccum (9549) -> 252604 (1.14 %, += 36.12 %) rel step time 1.13 DoMapAccum (2191) -> 238201 (1.08 %, += 37.19 %) rel step time 0.89 Map (9549) -> 237074 (1.07 %, += 38.26 %) rel step time 2.50 Map (2191) -> 236781 (1.07 %, += 39.33 %) rel step time 1.00 DoMapAccum-Aux (2191) -> 232387 (1.05 %, += 40.38 %) rel step time 0.93 RemoveDebugInfoRec (8023) -> 225523 (1.02 %, += 41.40 %) rel step time 0.95 SetScopeIDs-Command (2191) -> 219817 (0.99 %, += 42.39 %) rel step time 1.50 OutlineConstants-OneCommand (2191) -> 219817 (0.99 %, += 43.39 %) rel step time 1.50 Map (5703) -> 211369 (0.95 %, += 44.34 %) rel step time 0.67 DoScan (8317) -> 205434 (0.93 %, += 45.27 %) rel step time 1.33 ConvertOneInterpretCommand (8023) -> 203892 (0.92 %, += 46.19 %) rel step time 0.64 ConvertOneInterpretCommand-Simple (8023) -> 200643 (0.91 %, += 47.10 %) rel step time 0.77 Apply (5703) -> 190271 (0.86 %, += 47.95 %) rel step time 0.69 IncCol (8317) -> 189837 (0.86 %, += 48.81 %) rel step time 0.62 Inc (8317) -> 186933 (0.84 %, += 49.66 %) rel step time 0.26 DoMapAccum-Aux (9549) -> 182314 (0.82 %, += 50.48 %) rel step time 0.91 DoMapAccum (4746) -> 181419 (0.82 %, += 51.30 %) rel step time 0.98 DoMapAccum-Aux (4746) -> 181383 (0.82 %, += 52.12 %) rel step time 0.97 CalcLabelsOffsets\1 (4746) -> 181383 (0.82 %, += 52.94 %) rel step time 1.05 OutlineStrings (8023) -> 181108 (0.82 %, += 53.76 %) rel step time 1.49 GenCommand-RASL (4746) -> 181072 (0.82 %, += 54.57 %) rel step time 3.21 Inc (4746) -> 178926 (0.81 %, += 55.38 %) rel step time 0.59 Map (8023) -> 176158 (0.80 %, += 56.18 %) rel step time 0.61 UnBracket (4746) -> 176083 (0.80 %, += 56.97 %) rel step time 0.47 NumberFromOpcode (0000) -> 176083 (0.80 %, += 57.77 %) rel step time 4.70 : AddFunctionNameComment\1 (8023) -> 173701 (0.78 %, += 58.55 %) rel step time 0.34 AddFunctionNameComment\1 (8023) -> 173701 (0.78 %, += 59.34 %) rel step time 0.67 AddBacktrackStackAllocationCommand\1 (8023) -> 172573 (0.78 %, += 60.12 %) rel step time 0.90 NormNumber (0871) -> 168116 (0.76 %, += 60.88 %) rel step time 0.98 DoScan-Var (8317) -> 159668 (0.72 %, += 61.60 %) rel step time 0.98 DoScan-Var$1?1 (8317) -> 157117 (0.71 %, += 62.31 %) rel step time 1.57 : PutCommand$3=1 (4746) -> 152114 (0.69 %, += 62.99 %) rel step time 0.15 : PutCommand$3:1 (4746) -> 152114 (0.69 %, += 63.68 %) rel step time 0.31 PutCommand$3=1 (4746) -> 152114 (0.69 %, += 64.37 %) rel step time 1.16 PutCommand$3:1 (4746) -> 152114 (0.69 %, += 65.05 %) rel step time 0.39 Apply (8897) -> 148577 (0.67 %, += 65.73 %) rel step time 0.48 Map (8897) -> 140502 (0.63 %, += 66.36 %) rel step time 0.91 __Step-Start (0000) -> 125291 (0.57 %, += 66.93 %) rel step time 0.58 __Step-End (0000) -> 125291 (0.57 %, += 67.49 %) rel step time 0.10 DoScan-CComment (8317) -> 111939 (0.51 %, += 68.00 %) rel step time 1.70 DoScan-Ident (8317) -> 109734 (0.50 %, += 68.49 %) rel step time 0.96 DoScan-Ident$1?1 (8317) -> 108721 (0.49 %, += 68.98 %) rel step time 1.62 Apply (8477) -> 104396 (0.47 %, += 69.46 %) rel step time 0.57 : DisplayLexerWarnings\1 (5703) -> 100274 (0.45 %, += 69.91 %) rel step time 0.48 DisplayLexerWarnings\1 (5703) -> 100274 (0.45 %, += 70.36 %) rel step time 0.69 Map (7236) -> 99736 (0.45 %, += 70.81 %) rel step time 1.32 Map (8477) -> 99121 (0.45 %, += 71.26 %) rel step time 0.59 Apply (7236) -> 88808 (0.40 %, += 71.66 %) rel step time 0.95 : Symb=1 (0871) -> 87529 (0.40 %, += 72.06 %) rel step time 0.00 : Symb:1 (0871) -> 87529 (0.40 %, += 72.45 %) rel step time 0.13 Symb=1 (0871) -> 87529 (0.40 %, += 72.85 %) rel step time 0.53 Symb:1 (0871) -> 87529 (0.40 %, += 73.24 %) rel step time 0.68 Symb (0000) -> 87529 (0.40 %, += 73.64 %) rel step time 1.21 CheckClassicTokens (8317) -> 86214 (0.39 %, += 74.03 %) rel step time 0.82 Symb-Nat (0871) -> 80587 (0.36 %, += 74.39 %) rel step time 0.44 StrFromMacroDigit (0871) -> 80587 (0.36 %, += 74.75 %) rel step time 0.15 NormNumber$9?1 (0871) -> 80587 (0.36 %, += 75.12 %) rel step time 0.89 NormNumber$7?1 (0871) -> 80587 (0.36 %, += 75.48 %) rel step time 0.59 AllDigits-SwFirst (0871) -> 80587 (0.36 %, += 75.85 %) rel step time 0.43 AllDigits (0871) -> 80587 (0.36 %, += 76.21 %) rel step time 0.87 : DoTokenChain:1 (6799) -> 72156 (0.33 %, += 76.54 %) rel step time 0.33 DoTokenChain:1 (6799) -> 72156 (0.33 %, += 76.86 %) rel step time 2.84 DoTokenChain (6799) -> 72156 (0.33 %, += 77.19 %) rel step time 1.46 TermCompare (0000) -> 71940 (0.32 %, += 77.51 %) rel step time 0.82 MapAccum (9549) -> 70290 (0.32 %, += 77.83 %) rel step time 0.69 RemovePos-Expr\1 (9549) -> 66898 (0.30 %, += 78.13 %) rel step time 1.06 : EnumerateVars-Expr\1 (9549) -> 65017 (0.29 %, += 78.43 %) rel step time 0.55 EnumerateVars-Expr\1 (9549) -> 65017 (0.29 %, += 78.72 %) rel step time 2.19 : PatchASTSrcPos-Expr\1 (5703) -> 64513 (0.29 %, += 79.01 %) rel step time 0.19 PatchASTSrcPos-Expr\1 (5703) -> 64513 (0.29 %, += 79.30 %) rel step time 1.27 FlatExpr\1 (8897) -> 63919 (0.29 %, += 79.59 %) rel step time 0.72 IsStartIdent (8317) -> 61646 (0.28 %, += 79.87 %) rel step time 1.36 IsStartIdent$2?1 (8317) -> 61502 (0.28 %, += 80.15 %) rel step time 0.37 DoScan$7?1 (8317) -> 58398 (0.26 %, += 80.41 %) rel step time 0.61 DoGenResult (8477) -> 57451 (0.26 %, += 80.67 %) rel step time 4.26 TextFromExpr-Prepare (7236) -> 55457 (0.25 %, += 80.92 %) rel step time 1.49 CollectStrings (0000) -> 52601 (0.24 %, += 81.16 %) rel step time 1.31 Reduce (0000) -> 50320 (0.23 %, += 81.39 %) rel step time 0.48 TextFromExpr\1 (7236) -> 50157 (0.23 %, += 81.61 %) rel step time 1.19 Offset (7236) -> 49284 (0.22 %, += 81.83 %) rel step time 0.98 IsPunctuation$1?1 (8317) -> 49233 (0.22 %, += 82.06 %) rel step time 1.45 IsPunctuation (8317) -> 49233 (0.22 %, += 82.28 %) rel step time 2.65 DoScan$8?1 (8317) -> 49233 (0.22 %, += 82.50 %) rel step time 1.68 RemoveAssigns-Result\1 (9549) -> 45494 (0.21 %, += 82.71 %) rel step time 0.52 ExtractNested-Result-Term (9549) -> 45494 (0.21 %, += 82.91 %) rel step time 1.30 NameNestedResultRec-Term (9549) -> 44410 (0.20 %, += 83.11 %) rel step time 1.57 PrintVar (0000) -> 43520 (0.20 %, += 83.31 %) rel step time 0.00 Lenw (0000) -> 41365 (0.19 %, += 83.50 %) rel step time 0.88 Sort (0000) -> 40746 (0.18 %, += 83.68 %) rel step time 0.89 SymbCompare (0000) -> 39343 (0.18 %, += 83.86 %) rel step time 0.31 : IncCol$2=1 (8317) -> 37906 (0.17 %, += 84.03 %) rel step time 0.00 IncCol$2=1 (8317) -> 37906 (0.17 %, += 84.20 %) rel step time 0.62 Apply (3398) -> 37428 (0.17 %, += 84.37 %) rel step time 0.32 ZeroHandle (0871) -> 37056 (0.17 %, += 84.54 %) rel step time 0.00 Get-Aux (0871) -> 37056 (0.17 %, += 84.70 %) rel step time 1.27 Autoopen (0871) -> 37056 (0.17 %, += 84.87 %) rel step time 0.00 DoLoadFile (2369) -> 37056 (0.17 %, += 85.04 %) rel step time 1.27 Get (0000) -> 37056 (0.17 %, += 85.21 %) rel step time 0.63 CreateCommandTag (4746) -> 36482 (0.16 %, += 85.37 %) rel step time 0.33 CreateCommandInfo (4746) -> 36482 (0.16 %, += 85.54 %) rel step time 1.00 PutWordLE (4746) -> 35744 (0.16 %, += 85.70 %) rel step time 0.68 Inc (8477) -> 35610 (0.16 %, += 85.86 %) rel step time 0.66 DoGenSubst (0177) -> 35358 (0.16 %, += 86.02 %) rel step time 23.69 : PatchASTSrcPos=1\1 (5703) -> 32783 (0.15 %, += 86.17 %) rel step time 0.72 PatchASTSrcPos=1\1 (5703) -> 32783 (0.15 %, += 86.31 %) rel step time 1.48 Apply (0000) -> 32739 (0.15 %, += 86.46 %) rel step time 0.74 : Sort$2=1\1:1 (2369) -> 32597 (0.15 %, += 86.61 %) rel step time 0.72 : Sort$2=1\1 (2369) -> 32597 (0.15 %, += 86.76 %) rel step time 0.00 Sort$2=1\1:1 (2369) -> 32597 (0.15 %, += 86.90 %) rel step time 0.37 Sort$2=1\1 (2369) -> 32597 (0.15 %, += 87.05 %) rel step time 1.09 Lexicon-Aux (2369) -> 32597 (0.15 %, += 87.20 %) rel step time 1.09 Lexicon (2369) -> 32597 (0.15 %, += 87.35 %) rel step time 0.74 IncLine (8317) -> 30503 (0.14 %, += 87.48 %) rel step time 0.37 : PutWordLE$2=1 (4746) -> 26808 (0.12 %, += 87.60 %) rel step time 0.00 PutWordLE$2=1 (4746) -> 26808 (0.12 %, += 87.72 %) rel step time 0.45 DoMapAccum (8897) -> 25857 (0.12 %, += 87.84 %) rel step time 2.34 ExtractAllVars (9549) -> 25057 (0.11 %, += 87.95 %) rel step time 0.94 RemovePos-Expr (0000) -> 24990 (0.11 %, += 88.07 %) rel step time 0.97 NumberFromBracket (0000) -> 24828 (0.11 %, += 88.18 %) rel step time 0.49 Main (3398) -> 24471 (0.11 %, += 88.29 %) rel step time 4.46 EnumerateVars-Expr (9549) -> 24198 (0.11 %, += 88.40 %) rel step time 1.97 PatchASTSrcPos-Expr (5703) -> 23910 (0.11 %, += 88.51 %) rel step time 1.01 FlatExpr (8897) -> 23646 (0.11 %, += 88.61 %) rel step time 0.51 DisplayName (0000) -> 21615 (0.10 %, += 88.71 %) rel step time 1.61 DoMapAccum-Aux (8897) -> 20902 (0.09 %, += 88.81 %) rel step time 0.54 IsVarType (8317) -> 20617 (0.09 %, += 88.90 %) rel step time 0.59 SafeDisplayName\1 (7236) -> 20602 (0.09 %, += 88.99 %) rel step time 0.00 DoScan$6?1 (8317) -> 20561 (0.09 %, += 89.09 %) rel step time 1.14 : DoScan-Var$2\1 (8317) -> 20518 (0.09 %, += 89.18 %) rel step time 0.59 Fetch (8317) -> 20518 (0.09 %, += 89.27 %) rel step time 1.14 DoScan-Var$2\1 (8317) -> 20518 (0.09 %, += 89.36 %) rel step time 1.14 Apply (8317) -> 20518 (0.09 %, += 89.46 %) rel step time 1.77 TokenChain (6799) -> 19873 (0.09 %, += 89.55 %) rel step time 1.14 OneOf (0000) -> 19614 (0.09 %, += 89.63 %) rel step time 0.58 DoScan-BuildKeyword (8317) -> 19456 (0.09 %, += 89.72 %) rel step time 1.25 DoScan-BuildKeyword$1?1 (8317) -> 19455 (0.09 %, += 89.81 %) rel step time 0.62 Apply (7106) -> 18996 (0.09 %, += 89.90 %) rel step time 0.00 Map (3398) -> 18020 (0.08 %, += 89.98 %) rel step time 0.63 VariableIndexTail (3398) -> 17805 (0.08 %, += 90.06 %) rel step time 2.59 : ReplicateVar\1 (8477) -> 17723 (0.08 %, += 90.14 %) rel step time 0.00 Sort$2=1 (2369) -> 17723 (0.08 %, += 90.22 %) rel step time 0.68 ReplicateVar\1 (8477) -> 17723 (0.08 %, += 90.30 %) rel step time 1.37 MakeVariableComment (8477) -> 17723 (0.08 %, += 90.38 %) rel step time 0.64 DisplayNameSuf (5501) -> 17263 (0.08 %, += 90.46 %) rel step time 0.00 CheckResult\1 (8897) -> 16192 (0.07 %, += 90.53 %) rel step time 2.20 DoScan-Chars (8317) -> 16169 (0.07 %, += 90.60 %) rel step time 2.20 DoMapAccum (3398) -> 16091 (0.07 %, += 90.67 %) rel step time 0.00 DoMapAccum-Aux (3398) -> 16089 (0.07 %, += 90.75 %) rel step time 1.41 AddLineNumber (3398) -> 16089 (0.07 %, += 90.82 %) rel step time 2.21 NormalizeToken (3398) -> 16049 (0.07 %, += 90.89 %) rel step time 1.46 PutVariableDebugTable\1 (7236) -> 15925 (0.07 %, += 90.96 %) rel step time 0.71 ReplicateVar (8477) -> 15925 (0.07 %, += 91.04 %) rel step time 0.71 MakeVarTable-Simple (8477) -> 15925 (0.07 %, += 91.11 %) rel step time 2.19 Pipe (8477) -> 15900 (0.07 %, += 91.18 %) rel step time 0.00 Fetch (8477) -> 15900 (0.07 %, += 91.25 %) rel step time 1.52 Fetch (9549) -> 15587 (0.07 %, += 91.32 %) rel step time 0.00 Apply (3931) -> 15094 (0.07 %, += 91.39 %) rel step time 1.56 RemoveAssigns-Result (9549) -> 14690 (0.07 %, += 91.46 %) rel step time 0.00 ExtractNested-Result=1 (9549) -> 14690 (0.07 %, += 91.52 %) rel step time 0.77 ExtractNested-Result (9549) -> 14690 (0.07 %, += 91.59 %) rel step time 1.55 OneOf (6799) -> 14374 (0.06 %, += 91.65 %) rel step time 1.69 CComment (3398) -> 14233 (0.06 %, += 91.72 %) rel step time 0.80 Map (7106) -> 14166 (0.06 %, += 91.78 %) rel step time 0.86 PrepareBracket (7106) -> 14164 (0.06 %, += 91.85 %) rel step time 2.51 NameNestedResultRec (9549) -> 14148 (0.06 %, += 91.91 %) rel step time 0.00 BuildString (7236) -> 14069 (0.06 %, += 91.97 %) rel step time 0.86 Name (3398) -> 13816 (0.06 %, += 92.04 %) rel step time 1.64 CheckBorrowed (9549) -> 13570 (0.06 %, += 92.10 %) rel step time 1.73 UpdateTable (2191) -> 13489 (0.06 %, += 92.16 %) rel step time 6.91 DoMapAccum (5955) -> 13057 (0.06 %, += 92.22 %) rel step time 1.80 Ord (0000) -> 12151 (0.05 %, += 92.27 %) rel step time 0.00 CheckPattern$3\1 (8897) -> 11651 (0.05 %, += 92.33 %) rel step time 0.98 UpdateBorrowedVars (9549) -> 11472 (0.05 %, += 92.38 %) rel step time 1.06 Apply (5064) -> 11186 (0.05 %, += 92.43 %) rel step time 0.00 ExtractVariables-Expr (0000) -> 10983 (0.05 %, += 92.48 %) rel step time 1.10 Apply (5955) -> 10857 (0.05 %, += 92.53 %) rel step time 0.00 : Pipe$2\1 (8477) -> 10600 (0.05 %, += 92.57 %) rel step time 0.00 Pipe$2\1 (8477) -> 10600 (0.05 %, += 92.62 %) rel step time 0.00 Apply (3990) -> 10600 (0.05 %, += 92.67 %) rel step time 1.14 DelAccumulator (9549) -> 10150 (0.05 %, += 92.72 %) rel step time 1.19 EnumerateVars-Expr\1$5\1 (9549) -> 9839 (0.04 %, += 92.76 %) rel step time 1.15 : PatchASTSrcPos-Sentence=1\1 (5703) -> 9766 (0.04 %, += 92.80 %) rel step time 1.24 PatchASTSrcPos-Sentence=1\1 (5703) -> 9766 (0.04 %, += 92.85 %) rel step time 1.16 Inc (9549) -> 9701 (0.04 %, += 92.89 %) rel step time 1.25 Inc (8023) -> 9187 (0.04 %, += 92.93 %) rel step time 0.00 BytesFromString (4746) -> 8927 (0.04 %, += 92.97 %) rel step time 0.00 Inc2 (0000) -> 8914 (0.04 %, += 93.01 %) rel step time 2.72 DoParseResult (7106) -> 8569 (0.04 %, += 93.05 %) rel step time 2.83 Fetch (4746) -> 8456 (0.04 %, += 93.09 %) rel step time 0.00 MapAccum (8023) -> 8307 (0.04 %, += 93.13 %) rel step time 0.00 Expression-CheckBrackets$1?1 (6799) -> 8289 (0.04 %, += 93.17 %) rel step time 1.37 Expression-CheckBrackets (6799) -> 8289 (0.04 %, += 93.20 %) rel step time 4.29 Expression (6799) -> 8289 (0.04 %, += 93.24 %) rel step time 0.00 Asciiz (4746) -> 8253 (0.04 %, += 93.28 %) rel step time 0.00 : DoTokenChain:1$6=2:1 (6799) -> 8154 (0.04 %, += 93.32 %) rel step time 0.00 : DoTokenChain:1$6=2 (6799) -> 8154 (0.04 %, += 93.35 %) rel step time 0.00 : DoTokenChain:1$6=1 (6799) -> 8154 (0.04 %, += 93.39 %) rel step time 0.00 DoTokenChain:1$6=2:1 (6799) -> 8154 (0.04 %, += 93.43 %) rel step time 1.49 DoTokenChain:1$6=2 (6799) -> 8154 (0.04 %, += 93.46 %) rel step time 1.49 DoTokenChain:1$6=1 (6799) -> 8154 (0.04 %, += 93.50 %) rel step time 0.00 Map (5955) -> 8050 (0.04 %, += 93.54 %) rel step time 0.00 Map (3931) -> 7861 (0.04 %, += 93.57 %) rel step time 0.00 Reduce (9549) -> 7768 (0.04 %, += 93.61 %) rel step time 1.56 MapAccum (5955) -> 7757 (0.04 %, += 93.64 %) rel step time 0.00 Map (3990) -> 7757 (0.04 %, += 93.68 %) rel step time 0.00 : UpdateFuncTable=1 (2191) -> 7189 (0.03 %, += 93.71 %) rel step time 0.00 UpdateFuncTable=1 (2191) -> 7189 (0.03 %, += 93.74 %) rel step time 0.00 UpdateFuncTable (2191) -> 7189 (0.03 %, += 93.77 %) rel step time 0.00 SwPunctuation (3398) -> 7045 (0.03 %, += 93.81 %) rel step time 3.44 Map (3995) -> 6914 (0.03 %, += 93.84 %) rel step time 0.00 Pass-CleanupRedudandExterns=1\1 (9549) -> 6914 (0.03 %, += 93.87 %) rel step time 5.26 FilterDeclarations (9549) -> 6914 (0.03 %, += 93.90 %) rel step time 10.30 OptTree-CleanupMarkup\1 (3995) -> 6878 (0.03 %, += 93.93 %) rel step time 3.30 Apply (3995) -> 6878 (0.03 %, += 93.96 %) rel step time 0.00 Pass-FoldEnums\1 (9549) -> 6878 (0.03 %, += 93.99 %) rel step time 0.00 Transform-Unit (3931) -> 6570 (0.03 %, += 94.02 %) rel step time 1.73 : UpdateIdentTable=1 (2191) -> 6300 (0.03 %, += 94.05 %) rel step time 0.00 UpdateIdentTable=1 (2191) -> 6300 (0.03 %, += 94.08 %) rel step time 3.85 UpdateIdentTable (2191) -> 6300 (0.03 %, += 94.11 %) rel step time 0.00 Inc (0177) -> 5866 (0.03 %, += 94.13 %) rel step time 0.00 MapAccum (2191) -> 5814 (0.03 %, += 94.16 %) rel step time 2.08 : OutlineConstants-OneCommand$3=1 (2191) -> 5715 (0.03 %, += 94.19 %) rel step time 0.00 OutlineConstants-OneCommand$3=1 (2191) -> 5715 (0.03 %, += 94.21 %) rel step time 6.36 SetScopeIDs-Commands (2191) -> 5706 (0.03 %, += 94.24 %) rel step time 4.11 OutlineConstants-Commands (2191) -> 5706 (0.03 %, += 94.26 %) rel step time 0.00 ConvertInterpretCommands (8023) -> 5706 (0.03 %, += 94.29 %) rel step time 1.99 FoldOpenELoops (0000) -> 5706 (0.03 %, += 94.31 %) rel step time 8.23 Inc (2191) -> 5648 (0.03 %, += 94.34 %) rel step time 0.00 Map (5064) -> 5629 (0.03 %, += 94.37 %) rel step time 2.02 SetScopeIDs (2191) -> 5593 (0.03 %, += 94.39 %) rel step time 2.17 OutlineConstants-RASLOnly (2191) -> 5593 (0.03 %, += 94.42 %) rel step time 4.20 MarkFunctionGenMode (2191) -> 5593 (0.03 %, += 94.44 %) rel step time 0.00 CalcDigest (2191) -> 5593 (0.03 %, += 94.47 %) rel step time 0.00 SetCookies (5064) -> 5593 (0.03 %, += 94.49 %) rel step time 0.00 ClassifyItems (8023) -> 5593 (0.03 %, += 94.52 %) rel step time 2.17 HashLittle2-Chars (0000) -> 5593 (0.03 %, += 94.54 %) rel step time 0.00 : HighLevelRASL\1 (5955) -> 5557 (0.03 %, += 94.57 %) rel step time 0.00 HighLevelRASL\1 (5955) -> 5557 (0.03 %, += 94.59 %) rel step time 2.18 HashFuncName (2191) -> 5557 (0.03 %, += 94.62 %) rel step time 0.00 CharFromScopeClass (2191) -> 5557 (0.03 %, += 94.64 %) rel step time 0.00 NameWithSign-Bytes (4746) -> 5549 (0.03 %, += 94.67 %) rel step time 2.18 Uniq (9549) -> 5309 (0.02 %, += 94.69 %) rel step time 2.14 : HighLevelRASL-Function=1\1=1 (5955) -> 5300 (0.02 %, += 94.72 %) rel step time 0.00 : HighLevelRASL-Function=1\1 (5955) -> 5300 (0.02 %, += 94.74 %) rel step time 0.00 : GenResult=1 (7236) -> 5300 (0.02 %, += 94.76 %) rel step time 0.00 : GenPattern=1 (7236) -> 5300 (0.02 %, += 94.79 %) rel step time 0.00 : GenInitSubst=5 (7236) -> 5300 (0.02 %, += 94.81 %) rel step time 0.00 : GenInitSubst=4 (7236) -> 5300 (0.02 %, += 94.84 %) rel step time 0.00 : GenInitSubst=3 (7236) -> 5300 (0.02 %, += 94.86 %) rel step time 0.00 : GenInitSubst=2 (7236) -> 5300 (0.02 %, += 94.88 %) rel step time 0.00 : GenInitSubst=1 (7236) -> 5300 (0.02 %, += 94.91 %) rel step time 2.29 : GenResult=1 (8477) -> 5300 (0.02 %, += 94.93 %) rel step time 0.00 : HighLevelRASL-Function-Disjoint=1\1 (3990) -> 5300 (0.02 %, += 94.96 %) rel step time 0.00 HighLevelRASL-Function=1\1=1 (5955) -> 5300 (0.02 %, += 94.98 %) rel step time 2.14 HighLevelRASL-Function=1\1 (5955) -> 5300 (0.02 %, += 95.00 %) rel step time 6.86 DoMapAccum-Aux (5955) -> 5300 (0.02 %, += 95.03 %) rel step time 2.29 GenResult=1 (7236) -> 5300 (0.02 %, += 95.05 %) rel step time 0.00 GenPattern=1 (7236) -> 5300 (0.02 %, += 95.08 %) rel step time 0.00 GenInitSubst=5 (7236) -> 5300 (0.02 %, += 95.10 %) rel step time 2.29 GenInitSubst=4 (7236) -> 5300 (0.02 %, += 95.12 %) rel step time 4.43 GenInitSubst=3 (7236) -> 5300 (0.02 %, += 95.15 %) rel step time 6.72 GenInitSubst=2 (7236) -> 5300 (0.02 %, += 95.17 %) rel step time 0.00 GenInitSubst=1 (7236) -> 5300 (0.02 %, += 95.19 %) rel step time 2.29 GenResult=1 (8477) -> 5300 (0.02 %, += 95.22 %) rel step time 4.43 GenResult-Simple:1 (8477) -> 5300 (0.02 %, += 95.24 %) rel step time 4.57 GenResult (8477) -> 5300 (0.02 %, += 95.27 %) rel step time 0.00 HighLevelRASL-Function-Disjoint=1\1 (3990) -> 5300 (0.02 %, += 95.29 %) rel step time 2.29 DoMapAccum (3990) -> 5300 (0.02 %, += 95.31 %) rel step time 0.00 TextFromExpr (0000) -> 5300 (0.02 %, += 95.34 %) rel step time 0.00 SentenceTail (0000) -> 5300 (0.02 %, += 95.36 %) rel step time 0.00 PutVariableDebugTable (0000) -> 5300 (0.02 %, += 95.39 %) rel step time 4.57 MakeVariableCommentTable (0000) -> 5300 (0.02 %, += 95.41 %) rel step time 0.00 GenSubst-Simple (0000) -> 5300 (0.02 %, += 95.43 %) rel step time 0.00 GenResult-Simple (0000) -> 5300 (0.02 %, += 95.46 %) rel step time 0.00 GenResult (0000) -> 5300 (0.02 %, += 95.48 %) rel step time 2.29 GenPattern (0000) -> 5300 (0.02 %, += 95.51 %) rel step time 2.29 GenInitSubst (0000) -> 5300 (0.02 %, += 95.53 %) rel step time 0.00 RemoveAssigns-Sentence (9549) -> 5215 (0.02 %, += 95.55 %) rel step time 0.00 DoUnCondition$1?1 (3931) -> 5168 (0.02 %, += 95.58 %) rel step time 2.34 GenCommand-RASL$33\1 (4746) -> 5141 (0.02 %, += 95.60 %) rel step time 0.00 : ExtractNested-Result-Term$2=1 (9549) -> 4998 (0.02 %, += 95.62 %) rel step time 2.42 NameNestedResultRec-Term$1=1 (9549) -> 4998 (0.02 %, += 95.65 %) rel step time 0.00 ExtractNested-Result-Term$2=1 (9549) -> 4998 (0.02 %, += 95.67 %) rel step time 0.00 Pass-AttachEntries (9549) -> 4996 (0.02 %, += 95.69 %) rel step time 9.70 : Pass-FlatNestedFuncs\1 (9549) -> 4960 (0.02 %, += 95.71 %) rel step time 0.00 Pass-RemovePos\1 (9549) -> 4960 (0.02 %, += 95.74 %) rel step time 0.00 Pass-RemoveBlocksAndAssigns\1 (9549) -> 4960 (0.02 %, += 95.76 %) rel step time 0.00 Pass-NameNestedFuncs\1 (9549) -> 4960 (0.02 %, += 95.78 %) rel step time 0.00 Pass-FlatNestedFuncs\1 (9549) -> 4960 (0.02 %, += 95.80 %) rel step time 0.00 Pass-EnumerateVariables\1 (9549) -> 4960 (0.02 %, += 95.82 %) rel step time 2.44 MapAccum (8897) -> 4955 (0.02 %, += 95.85 %) rel step time 0.00 RemovePos-Blocks (9549) -> 4955 (0.02 %, += 95.87 %) rel step time 0.00 RemoveAssigns-WindBlocks (9549) -> 4955 (0.02 %, += 95.89 %) rel step time 0.00 NameNestedResult (9549) -> 4955 (0.02 %, += 95.91 %) rel step time 0.00 NameNestedBlocks=1 (9549) -> 4955 (0.02 %, += 95.94 %) rel step time 0.00 NameNestedBlocks (9549) -> 4955 (0.02 %, += 95.96 %) rel step time 0.00 ExtractNested-Pattern (9549) -> 4955 (0.02 %, += 95.98 %) rel step time 4.89 ExtractNested-Conditions (9549) -> 4955 (0.02 %, += 96.00 %) rel step time 0.00 EnumerateVars-Blocks (9549) -> 4955 (0.02 %, += 96.03 %) rel step time 4.74 EnumerateVars-Assignments (9549) -> 4955 (0.02 %, += 96.05 %) rel step time 0.00 DoParsePattern (7106) -> 4895 (0.02 %, += 96.07 %) rel step time 4.80 DoMapAccum (5703) -> 4886 (0.02 %, += 96.09 %) rel step time 0.00 DoCheckAssignments (8897) -> 4883 (0.02 %, += 96.11 %) rel step time 2.48 CheckResult (8897) -> 4883 (0.02 %, += 96.14 %) rel step time 2.33 CheckPattern (8897) -> 4883 (0.02 %, += 96.16 %) rel step time 0.00 CheckBlocks (8897) -> 4883 (0.02 %, += 96.18 %) rel step time 0.00 PatchASTSrcPos-Blocks (5703) -> 4883 (0.02 %, += 96.20 %) rel step time 2.33 PatchASTSrcPos-Item (5703) -> 4846 (0.02 %, += 96.22 %) rel step time 0.00 Fetch (7106) -> 4832 (0.02 %, += 96.25 %) rel step time 2.51 DoMapAccum-Aux (5703) -> 4814 (0.02 %, += 96.27 %) rel step time 0.00 : RemoveAssigns-Sentence$2=3 (9549) -> 4787 (0.02 %, += 96.29 %) rel step time 0.00 : RemoveAssigns-Sentence$2=2 (9549) -> 4787 (0.02 %, += 96.31 %) rel step time 0.00 : RemoveAssigns-Sentence$2=1 (9549) -> 4787 (0.02 %, += 96.33 %) rel step time 0.00 : ExtractNested-Sentence=3 (9549) -> 4787 (0.02 %, += 96.35 %) rel step time 0.00 : ExtractNested-Sentence=2 (9549) -> 4787 (0.02 %, += 96.38 %) rel step time 0.00 : ExtractNested-Sentence=1 (9549) -> 4787 (0.02 %, += 96.40 %) rel step time 0.00 RemoveAssigns-Sentence$2=3 (9549) -> 4787 (0.02 %, += 96.42 %) rel step time 0.00 RemoveAssigns-Sentence$2=2 (9549) -> 4787 (0.02 %, += 96.44 %) rel step time 0.00 RemoveAssigns-Sentence$2=1 (9549) -> 4787 (0.02 %, += 96.46 %) rel step time 2.53 RemoveAssigns-Conditions (9549) -> 4787 (0.02 %, += 96.48 %) rel step time 2.37 ExtractNested-Sentence=3 (9549) -> 4787 (0.02 %, += 96.51 %) rel step time 0.00 ExtractNested-Sentence=2 (9549) -> 4787 (0.02 %, += 96.53 %) rel step time 2.37 ExtractNested-Sentence=1 (9549) -> 4787 (0.02 %, += 96.55 %) rel step time 0.00 ExtractNested-Sentence (9549) -> 4787 (0.02 %, += 96.57 %) rel step time 4.90 NameListTags (6799) -> 4786 (0.02 %, += 96.59 %) rel step time 2.53 CheckSpecsAndDrives=1\1 (8897) -> 4708 (0.02 %, += 96.61 %) rel step time 2.41 CheckSpecs-Formats\1 (8897) -> 4708 (0.02 %, += 96.64 %) rel step time 0.00 CreateMetaFunctions$1=1\1 (5703) -> 4708 (0.02 %, += 96.66 %) rel step time 0.00 : ExtractNested-Result-Term$4=1 (9549) -> 4700 (0.02 %, += 96.68 %) rel step time 0.00 ExtractNested-Result-Term$4=1 (9549) -> 4700 (0.02 %, += 96.70 %) rel step time 0.00 FindInvalidEntry\1 (8897) -> 4687 (0.02 %, += 96.72 %) rel step time 0.00 DoUnCondition (3931) -> 4570 (0.02 %, += 96.74 %) rel step time 2.49 SeparateDefines\1 (8897) -> 4543 (0.02 %, += 96.76 %) rel step time 0.00 CheckSpecsAndDrives=1\1$2?1 (8897) -> 4543 (0.02 %, += 96.78 %) rel step time 2.67 FindInvalidDriveInline\1$1?1 (8897) -> 4408 (0.02 %, += 96.80 %) rel step time 0.00 FindInvalidDriveInline\1 (8897) -> 4408 (0.02 %, += 96.82 %) rel step time 0.00 CheckValidUsings$5\1 (8897) -> 4408 (0.02 %, += 96.84 %) rel step time 0.00 : EnumerateVars-Body$1\1=4 (9549) -> 4359 (0.02 %, += 96.86 %) rel step time 0.00 : EnumerateVars-Body$1\1=3 (9549) -> 4359 (0.02 %, += 96.88 %) rel step time 0.00 : EnumerateVars-Body$1\1=2 (9549) -> 4359 (0.02 %, += 96.90 %) rel step time 0.00 : EnumerateVars-Body$1\1=1 (9549) -> 4359 (0.02 %, += 96.92 %) rel step time 0.00 : EnumerateVars-Body$1\1 (9549) -> 4359 (0.02 %, += 96.94 %) rel step time 0.00 RemovePos-Body$1\1 (9549) -> 4359 (0.02 %, += 96.96 %) rel step time 5.39 NameNestedAssigns (9549) -> 4359 (0.02 %, += 96.98 %) rel step time 0.00 EnumerateVars-Body$1\1=4 (9549) -> 4359 (0.02 %, += 97.00 %) rel step time 0.00 EnumerateVars-Body$1\1=3 (9549) -> 4359 (0.02 %, += 97.02 %) rel step time 0.00 EnumerateVars-Body$1\1=2 (9549) -> 4359 (0.02 %, += 97.04 %) rel step time 0.00 EnumerateVars-Body$1\1=1 (9549) -> 4359 (0.02 %, += 97.06 %) rel step time 0.00 EnumerateVars-Body$1\1 (9549) -> 4359 (0.02 %, += 97.08 %) rel step time 2.78 : CheckSentence=4 (8897) -> 4287 (0.02 %, += 97.10 %) rel step time 0.00 : CheckSentence=3 (8897) -> 4287 (0.02 %, += 97.12 %) rel step time 0.00 : CheckSentence=2 (8897) -> 4287 (0.02 %, += 97.14 %) rel step time 0.00 : CheckSentence=1 (8897) -> 4287 (0.02 %, += 97.16 %) rel step time 2.65 : PatchASTSrcPos-Sentence=1 (5703) -> 4287 (0.02 %, += 97.17 %) rel step time 0.00 CheckValidUsings (8897) -> 4287 (0.02 %, += 97.19 %) rel step time 22.26 CheckSentence=4 (8897) -> 4287 (0.02 %, += 97.21 %) rel step time 0.00 CheckSentence=3 (8897) -> 4287 (0.02 %, += 97.23 %) rel step time 5.30 CheckSentence=2 (8897) -> 4287 (0.02 %, += 97.25 %) rel step time 0.00 CheckSentence=1 (8897) -> 4287 (0.02 %, += 97.27 %) rel step time 0.00 CheckSentence (8897) -> 4287 (0.02 %, += 97.29 %) rel step time 0.00 PatchASTSrcPos-Sentence=1 (5703) -> 4287 (0.02 %, += 97.31 %) rel step time 2.83 PatchASTSrcPos-Sentence (5703) -> 4287 (0.02 %, += 97.33 %) rel step time 0.00 PatchASTSrcPos-Chain (5703) -> 4287 (0.02 %, += 97.35 %) rel step time 0.00 Pattern (6799) -> 4227 (0.02 %, += 97.37 %) rel step time 2.69 Result-Blocks (6799) -> 4173 (0.02 %, += 97.39 %) rel step time 0.00 NameNestedResultRec-Term$3=1 (9549) -> 4158 (0.02 %, += 97.41 %) rel step time 0.00 EnumerateVars-Expr\1$7\1 (9549) -> 4158 (0.02 %, += 97.42 %) rel step time 0.00 : SentencePart-AfterPattern:1$1=2 (6799) -> 4062 (0.02 %, += 97.44 %) rel step time 0.00 : SentencePart-AfterPattern:1 (6799) -> 4062 (0.02 %, += 97.46 %) rel step time 0.00 UnitStartToken (6799) -> 4062 (0.02 %, += 97.48 %) rel step time 2.80 SentenceTail (6799) -> 4062 (0.02 %, += 97.50 %) rel step time 0.00 SentencePart-AfterPattern:1$1?1 (6799) -> 4062 (0.02 %, += 97.52 %) rel step time 0.00 SentencePart-AfterPattern:1$1=2 (6799) -> 4062 (0.02 %, += 97.53 %) rel step time 2.98 SentencePart-AfterPattern:1 (6799) -> 4062 (0.02 %, += 97.55 %) rel step time 5.97 SentencePart-AfterPattern (6799) -> 4062 (0.02 %, += 97.57 %) rel step time 0.00 SentencePart (6799) -> 4062 (0.02 %, += 97.59 %) rel step time 0.00 Result (6799) -> 4062 (0.02 %, += 97.61 %) rel step time 0.00 DoProgram$1?2 (6799) -> 4062 (0.02 %, += 97.63 %) rel step time 0.00 DoProgram$1?1 (6799) -> 4062 (0.02 %, += 97.64 %) rel step time 0.00 DoProgram (6799) -> 4062 (0.02 %, += 97.66 %) rel step time 0.00 : Unit:1 (6799) -> 3922 (0.02 %, += 97.68 %) rel step time 2.90 : DoProgram$1=3 (6799) -> 3922 (0.02 %, += 97.70 %) rel step time 0.00 Unit:1 (6799) -> 3922 (0.02 %, += 97.72 %) rel step time 0.00 Unit (6799) -> 3922 (0.02 %, += 97.73 %) rel step time 0.00 DoProgram$1=3 (6799) -> 3922 (0.02 %, += 97.75 %) rel step time 0.00 StringLiteral (3398) -> 3736 (0.02 %, += 97.77 %) rel step time 0.00 HardItem (3931) -> 3589 (0.02 %, += 97.78 %) rel step time 3.17 : NameNestedBody$2\1=1 (9549) -> 3510 (0.02 %, += 97.80 %) rel step time 0.00 : NameNestedBody$2\1 (9549) -> 3510 (0.02 %, += 97.82 %) rel step time 0.00 NameNestedBody$2\1=1 (9549) -> 3510 (0.02 %, += 97.83 %) rel step time 6.90 NameNestedBody$2\1 (9549) -> 3510 (0.02 %, += 97.85 %) rel step time 0.00 : SentenceTail$2=1 (6799) -> 3486 (0.02 %, += 97.86 %) rel step time 0.00 : Block-AfterSentence$1:1 (6799) -> 3486 (0.02 %, += 97.88 %) rel step time 0.00 SentenceTail$2=1 (6799) -> 3486 (0.02 %, += 97.89 %) rel step time 0.00 Sentence (6799) -> 3486 (0.02 %, += 97.91 %) rel step time 0.00 CheckSentenceEnd (6799) -> 3486 (0.02 %, += 97.93 %) rel step time 0.00 Block-AfterSentence$1:1 (6799) -> 3486 (0.02 %, += 97.94 %) rel step time 0.00 Block-AfterSentence (6799) -> 3486 (0.02 %, += 97.96 %) rel step time 3.26 Pass-AttachEntries$6?1 (9549) -> 3451 (0.02 %, += 97.97 %) rel step time 0.00 AnalyzeFile$1?2 (8133) -> 3440 (0.02 %, += 97.99 %) rel step time 0.00 Fetch (3398) -> 3314 (0.01 %, += 98.00 %) rel step time 0.00 PatchASTSrcPos-Item$5?1 (5703) -> 3307 (0.01 %, += 98.02 %) rel step time 0.00 VariableStart (3398) -> 3306 (0.01 %, += 98.03 %) rel step time 0.00 VariableIndex (3398) -> 3306 (0.01 %, += 98.05 %) rel step time 7.33 NormalizeToken$19\1 (3398) -> 3306 (0.01 %, += 98.06 %) rel step time 0.00 DoTokenChain-AfterCall (6799) -> 3304 (0.01 %, += 98.08 %) rel step time 3.44 NameList-DoNames (6799) -> 3298 (0.01 %, += 98.09 %) rel step time 3.44 Mu (6799) -> 3298 (0.01 %, += 98.11 %) rel step time 0.00 __Meta_Mu (0000) -> 3298 (0.01 %, += 98.12 %) rel step time 3.44 DoScan$15?1 (8317) -> 3248 (0.01 %, += 98.14 %) rel step time 3.73 CreateMetaFunctions$1=1\1$3?1 (5703) -> 3235 (0.01 %, += 98.15 %) rel step time 7.49 DoBindBrackets (3931) -> 3148 (0.01 %, += 98.17 %) rel step time 3.85 SetFunctionsScopeClass=1\1 (8023) -> 2980 (0.01 %, += 98.18 %) rel step time 16.01 BytesFromFunctionList\1 (4746) -> 2980 (0.01 %, += 98.19 %) rel step time 0.00 DoScan-Keyword (8317) -> 2893 (0.01 %, += 98.21 %) rel step time 0.00 DoScan-BuildKeyword$2?1 (8317) -> 2893 (0.01 %, += 98.22 %) rel step time 0.00 Sub-Digits (0871) -> 2879 (0.01 %, += 98.23 %) rel step time 0.00 ResolveLabeledDestinations (4746) -> 2879 (0.01 %, += 98.25 %) rel step time 16.31 Sub (0000) -> 2879 (0.01 %, += 98.26 %) rel step time 0.00 TkExtern (6799) -> 2872 (0.01 %, += 98.27 %) rel step time 4.22 : Max$1:1 (2369) -> 2843 (0.01 %, += 98.28 %) rel step time 0.00 : ResolveLabeledDestinations$1=2 (4746) -> 2843 (0.01 %, += 98.30 %) rel step time 0.00 : ResolveLabeledDestinations$1=1 (4746) -> 2843 (0.01 %, += 98.31 %) rel step time 0.00 : ResolveLabeledDestinations$1:1$1=1 (4746) -> 2843 (0.01 %, += 98.32 %) rel step time 0.00 : ResolveLabeledDestinations$1:1 (4746) -> 2843 (0.01 %, += 98.34 %) rel step time 0.00 OutlineConstants-OneCommand$9=1 (2191) -> 2843 (0.01 %, += 98.35 %) rel step time 0.00 Max$1:1 (2369) -> 2843 (0.01 %, += 98.36 %) rel step time 0.00 ConvertOneInterpretCommand$1=1 (8023) -> 2843 (0.01 %, += 98.37 %) rel step time 0.00 HighLevelRASL-Function-Disjoint=2\1 (3990) -> 2843 (0.01 %, += 98.39 %) rel step time 0.00 DoMapAccum-Aux (3990) -> 2843 (0.01 %, += 98.40 %) rel step time 0.00 ResolveLabeledDestinations$1=2 (4746) -> 2843 (0.01 %, += 98.41 %) rel step time 0.00 ResolveLabeledDestinations$1=1 (4746) -> 2843 (0.01 %, += 98.43 %) rel step time 25.04 ResolveLabeledDestinations$1:1$1=1 (4746) -> 2843 (0.01 %, += 98.44 %) rel step time 4.00 ResolveLabeledDestinations$1:1 (4746) -> 2843 (0.01 %, += 98.45 %) rel step time 0.00 Max (0000) -> 2843 (0.01 %, += 98.46 %) rel step time 0.00 PutBlock\1 (4746) -> 2677 (0.01 %, += 98.48 %) rel step time 0.00 PutBlock (4746) -> 2677 (0.01 %, += 98.49 %) rel step time 0.00 BlockTypeNumber (0000) -> 2677 (0.01 %, += 98.50 %) rel step time 4.24 BytesFromIdentList\1 (4746) -> 2668 (0.01 %, += 98.51 %) rel step time 0.00 PrepareFunctionsRASL (8023) -> 2605 (0.01 %, += 98.52 %) rel step time 0.00 GlueFunctionRASLs\1 (8023) -> 2605 (0.01 %, += 98.54 %) rel step time 0.00 GenCommand-Globals (4746) -> 2605 (0.01 %, += 98.55 %) rel step time 13.37 NameList (6799) -> 2560 (0.01 %, += 98.56 %) rel step time 0.00 : OutlineConstants-OneCommand$8=1 (2191) -> 2464 (0.01 %, += 98.57 %) rel step time 0.00 OutlineConstants-OneCommand$8=1 (2191) -> 2464 (0.01 %, += 98.58 %) rel step time 4.61 : HighLevelRASL-Function=1 (5955) -> 2457 (0.01 %, += 98.59 %) rel step time 0.00 : OutlineConstants-RASLOnly$1=1 (2191) -> 2457 (0.01 %, += 98.60 %) rel step time 0.00 : PrepareFunctionsRASL$1=4 (8023) -> 2457 (0.01 %, += 98.62 %) rel step time 0.00 : PrepareFunctionsRASL$1=3 (8023) -> 2457 (0.01 %, += 98.63 %) rel step time 4.62 : PrepareFunctionsRASL$1=2 (8023) -> 2457 (0.01 %, += 98.64 %) rel step time 0.00 : PrepareFunctionsRASL$1=1 (8023) -> 2457 (0.01 %, += 98.65 %) rel step time 0.00 : HighLevelRASL-Function-Disjoint=2 (3990) -> 2457 (0.01 %, += 98.66 %) rel step time 0.00 : HighLevelRASL-Function-Disjoint=1 (3990) -> 2457 (0.01 %, += 98.67 %) rel step time 0.00 HighLevelRASL-Function=1 (5955) -> 2457 (0.01 %, += 98.68 %) rel step time 0.00 HighLevelRASL-Function-Aux (5955) -> 2457 (0.01 %, += 98.69 %) rel step time 0.00 HighLevelRASL-Function (5955) -> 2457 (0.01 %, += 98.70 %) rel step time 4.62 GenSentenceFunc (5955) -> 2457 (0.01 %, += 98.72 %) rel step time 0.00 GenResultFuncs (5955) -> 2457 (0.01 %, += 98.73 %) rel step time 0.00 OutlineConstants-RASLOnly$1=1 (2191) -> 2457 (0.01 %, += 98.74 %) rel step time 0.00 RemoveDebugInfo (8023) -> 2457 (0.01 %, += 98.75 %) rel step time 0.00 PrepareFunctionsRASL$1=4 (8023) -> 2457 (0.01 %, += 98.76 %) rel step time 0.00 PrepareFunctionsRASL$1=3 (8023) -> 2457 (0.01 %, += 98.77 %) rel step time 0.00 PrepareFunctionsRASL$1=2 (8023) -> 2457 (0.01 %, += 98.78 %) rel step time 0.00 PrepareFunctionsRASL$1=1 (8023) -> 2457 (0.01 %, += 98.79 %) rel step time 0.00 AddFunctionNameComment (8023) -> 2457 (0.01 %, += 98.80 %) rel step time 9.25 AddBacktrackStackAllocationCommand:1 (8023) -> 2457 (0.01 %, += 98.81 %) rel step time 0.00 AddBacktrackStackAllocationCommand (8023) -> 2457 (0.01 %, += 98.83 %) rel step time 0.00 MapAccum (3990) -> 2457 (0.01 %, += 98.84 %) rel step time 0.00 HighLevelRASL-Function-Disjoint\1 (3990) -> 2457 (0.01 %, += 98.85 %) rel step time 4.93 HighLevelRASL-Function-Disjoint=2 (3990) -> 2457 (0.01 %, += 98.86 %) rel step time 4.93 HighLevelRASL-Function-Disjoint=1 (3990) -> 2457 (0.01 %, += 98.87 %) rel step time 0.00 Fetch (3990) -> 2457 (0.01 %, += 98.88 %) rel step time 4.62 HighLevelRASL-Function-Disjoint (0000) -> 2457 (0.01 %, += 98.89 %) rel step time 0.00 : OutlineConstants-OneCommand$6=1 (2191) -> 2452 (0.01 %, += 98.90 %) rel step time 4.94 OutlineConstants-OneCommand$6=1 (2191) -> 2452 (0.01 %, += 98.91 %) rel step time 0.00 SafeDisplayName (7236) -> 2452 (0.01 %, += 98.93 %) rel step time 0.00 IsTerm (3532) -> 2310 (0.01 %, += 98.94 %) rel step time 0.00 Add-Nat (0871) -> 2246 (0.01 %, += 98.95 %) rel step time 0.00 RemoveAssigns-Sentences (9549) -> 2149 (0.01 %, += 98.96 %) rel step time 0.00 ExtractNestedRec (9549) -> 2149 (0.01 %, += 98.97 %) rel step time 0.00 DoT0 (3931) -> 2122 (0.01 %, += 98.98 %) rel step time 0.00 UnCondition (3931) -> 2113 (0.01 %, += 98.98 %) rel step time 0.00 ExtractNestedRec$1=1 (9549) -> 2113 (0.01 %, += 98.99 %) rel step time 0.00 TextFromExpr-Prepare-Chars (7236) -> 2075 (0.01 %, += 99.00 %) rel step time 0.00 DoPatternSteps:1$1?1 (3931) -> 2031 (0.01 %, += 99.01 %) rel step time 0.00 Reduce (8897) -> 1968 (0.01 %, += 99.02 %) rel step time 0.00 LoadSource\1 (3398) -> 1925 (0.01 %, += 99.03 %) rel step time 0.00 Inc (3398) -> 1925 (0.01 %, += 99.04 %) rel step time 0.00 Unique (0000) -> 1862 (0.01 %, += 99.05 %) rel step time 0.00 IncVarOffset (0000) -> 1798 (0.01 %, += 99.06 %) rel step time 0.00 DoNumb (0871) -> 1797 (0.01 %, += 99.06 %) rel step time 6.32 CreateContext$2\1 (9549) -> 1730 (0.01 %, += 99.07 %) rel step time 0.00 : DoPatternSteps:1 (3931) -> 1726 (0.01 %, += 99.08 %) rel step time 0.00 DoPatternSteps:1 (3931) -> 1726 (0.01 %, += 99.09 %) rel step time 0.00 DoPatternSteps (3931) -> 1726 (0.01 %, += 99.10 %) rel step time 0.00 RemovePos-Body (9549) -> 1721 (0.01 %, += 99.10 %) rel step time 0.00 NameNestedBody (9549) -> 1721 (0.01 %, += 99.11 %) rel step time 13.64 EnumerateVars-Body (9549) -> 1721 (0.01 %, += 99.12 %) rel step time 0.00 DoMapAccum (7236) -> 1658 (0.01 %, += 99.13 %) rel step time 0.00 Root (3398) -> 1618 (0.01 %, += 99.13 %) rel step time 0.00 CheckFunctionBodyRec (8897) -> 1613 (0.01 %, += 99.14 %) rel step time 0.00 PatchASTSrcPos-Body (5703) -> 1613 (0.01 %, += 99.15 %) rel step time 0.00 DoParseResult$10\2 (7106) -> 1601 (0.01 %, += 99.16 %) rel step time 0.00 DoParseResult$10\1 (7106) -> 1601 (0.01 %, += 99.16 %) rel step time 0.00 DoPatternSteps:1$2?1 (3931) -> 1558 (0.01 %, += 99.17 %) rel step time 0.00 Block (6799) -> 1530 (0.01 %, += 99.18 %) rel step time 0.00 DirectorySeparators (0871) -> 1406 (0.01 %, += 99.18 %) rel step time 0.00 ClassicError (6799) -> 1401 (0.01 %, += 99.19 %) rel step time 0.00 : OutlineConstants-Metatable\1=2 (2191) -> 1384 (0.01 %, += 99.20 %) rel step time 0.00 : OutlineConstants-Metatable\1=1 (2191) -> 1384 (0.01 %, += 99.20 %) rel step time 8.76 : CreateMetaFunctions$1\1 (5703) -> 1384 (0.01 %, += 99.21 %) rel step time 0.00 OutlineConstants-Metatable\1=2 (2191) -> 1384 (0.01 %, += 99.21 %) rel step time 0.00 OutlineConstants-Metatable\1=1 (2191) -> 1384 (0.01 %, += 99.22 %) rel step time 8.76 OutlineConstants-Metatable\1 (2191) -> 1384 (0.01 %, += 99.23 %) rel step time 0.00 RemovePos-Body$3\1 (9549) -> 1384 (0.01 %, += 99.23 %) rel step time 0.00 CreateMetaFunctions$1\1 (5703) -> 1384 (0.01 %, += 99.24 %) rel step time 0.00 GenCommand-Globals$4\1 (4746) -> 1384 (0.01 %, += 99.25 %) rel step time 0.00 : Pass-FlatNestedFuncs\1$1=1 (9549) -> 1344 (0.01 %, += 99.25 %) rel step time 0.00 Pass-FlatNestedFuncs\1$1=1 (9549) -> 1344 (0.01 %, += 99.26 %) rel step time 0.00 ExtractNested=1 (9549) -> 1344 (0.01 %, += 99.26 %) rel step time 0.00 ExtractNested (9549) -> 1344 (0.01 %, += 99.27 %) rel step time 0.00 MakeSavers=1\1 (7236) -> 1252 (0.01 %, += 99.28 %) rel step time 9.68 DoMapAccum-Aux (7236) -> 1252 (0.01 %, += 99.28 %) rel step time 0.00 CheckFunctionBody (8897) -> 1236 (0.01 %, += 99.29 %) rel step time 0.00 StringLiteral-Start (3398) -> 1199 (0.01 %, += 99.29 %) rel step time 0.00 StringLiteral-Quote (3398) -> 1199 (0.01 %, += 99.30 %) rel step time 0.00 : IsDirectorySeparator:1 (0871) -> 1192 (0.01 %, += 99.30 %) rel step time 0.00 AssignOrCondition (9549) -> 1192 (0.01 %, += 99.31 %) rel step time 0.00 IsDirectorySeparator:1 (0871) -> 1192 (0.01 %, += 99.31 %) rel step time 0.00 GetBaseName$1?1 (4171) -> 1192 (0.01 %, += 99.32 %) rel step time 0.00 IsDirectorySeparator (0000) -> 1192 (0.01 %, += 99.32 %) rel step time 0.00 Mul-Digits (0871) -> 1179 (0.01 %, += 99.33 %) rel step time 0.00 Unit:1$7?1 (6799) -> 1164 (0.01 %, += 99.33 %) rel step time 0.00 Function-AfterBlock (6799) -> 1164 (0.01 %, += 99.34 %) rel step time 0.00 Function (6799) -> 1164 (0.01 %, += 99.35 %) rel step time 0.00 Solve-Aux (3532) -> 1155 (0.01 %, += 99.35 %) rel step time 10.49 : Add-Nat$1:1 (0871) -> 1123 (0.01 %, += 99.36 %) rel step time 0.00 Mul-Nat-Line (0871) -> 1123 (0.01 %, += 99.36 %) rel step time 0.00 DoNumb-AddDigit (0871) -> 1123 (0.01 %, += 99.37 %) rel step time 0.00 Add-Nat$1:1 (0871) -> 1123 (0.01 %, += 99.37 %) rel step time 0.00 EscapeChar (0000) -> 1113 (0.01 %, += 99.38 %) rel step time 0.00 : EscapeChar$7:1 (3807) -> 1060 (0.00 %, += 99.38 %) rel step time 0.00 EscapeChar$7:1 (3807) -> 1060 (0.00 %, += 99.39 %) rel step time 21.43 Inc (7236) -> 1050 (0.00 %, += 99.39 %) rel step time 0.00 Map (3532) -> 1023 (0.00 %, += 99.39 %) rel step time 0.00 MakeVariables (3931) -> 889 (0.00 %, += 99.40 %) rel step time 13.63 DoParseBlock (7106) -> 884 (0.00 %, += 99.40 %) rel step time 0.00 Fetch (7236) -> 872 (0.00 %, += 99.41 %) rel step time 0.00 CollectStrings$1\1 (7236) -> 872 (0.00 %, += 99.41 %) rel step time 0.00 ExtractVariables-Sentences (3931) -> 864 (0.00 %, += 99.41 %) rel step time 0.00 CppComment (3398) -> 828 (0.00 %, += 99.42 %) rel step time 0.00 Solve-Aux$1?1 (3532) -> 825 (0.00 %, += 99.42 %) rel step time 0.00 ParseBlocks (7106) -> 824 (0.00 %, += 99.43 %) rel step time 0.00 : ParseSentencePart=3 (7106) -> 821 (0.00 %, += 99.43 %) rel step time 0.00 : ParseSentencePart=2\1 (7106) -> 821 (0.00 %, += 99.43 %) rel step time 0.00 : ParseSentencePart=2 (7106) -> 821 (0.00 %, += 99.44 %) rel step time 0.00 : ParseSentence:1 (7106) -> 821 (0.00 %, += 99.44 %) rel step time 0.00 : ParseResult=2 (7106) -> 821 (0.00 %, += 99.44 %) rel step time 0.00 ParseSentencePart=3 (7106) -> 821 (0.00 %, += 99.45 %) rel step time 0.00 ParseSentencePart=2\1 (7106) -> 821 (0.00 %, += 99.45 %) rel step time 0.00 ParseSentencePart=2 (7106) -> 821 (0.00 %, += 99.46 %) rel step time 0.00 ParseSentencePart=1 (7106) -> 821 (0.00 %, += 99.46 %) rel step time 0.00 ParseSentencePart (7106) -> 821 (0.00 %, += 99.46 %) rel step time 0.00 ParseSentence=1 (7106) -> 821 (0.00 %, += 99.47 %) rel step time 0.00 ParseSentence:1 (7106) -> 821 (0.00 %, += 99.47 %) rel step time 0.00 ParseSentence (7106) -> 821 (0.00 %, += 99.47 %) rel step time 0.00 ParseResult=2 (7106) -> 821 (0.00 %, += 99.48 %) rel step time 0.00 ParseResult=1 (7106) -> 821 (0.00 %, += 99.48 %) rel step time 0.00 ParseResult (7106) -> 821 (0.00 %, += 99.49 %) rel step time 0.00 ParsePattern (7106) -> 821 (0.00 %, += 99.49 %) rel step time 0.00 : ExtractNested-Result-Term$5=2\1 (9549) -> 805 (0.00 %, += 99.49 %) rel step time 0.00 : ExtractNested-Result-Term$5=2 (9549) -> 805 (0.00 %, += 99.50 %) rel step time 0.00 : ExtractNested-Result-Term$5=1 (9549) -> 805 (0.00 %, += 99.50 %) rel step time 0.00 Pass-FlatNestedFuncs\1$1\1?1 (9549) -> 805 (0.00 %, += 99.50 %) rel step time 15.05 Pass-FlatNestedFuncs\1$1\1 (9549) -> 805 (0.00 %, += 99.51 %) rel step time 0.00 ExtractNested-Result-Term$5=2\1 (9549) -> 805 (0.00 %, += 99.51 %) rel step time 0.00 ExtractNested-Result-Term$5=2 (9549) -> 805 (0.00 %, += 99.51 %) rel step time 0.00 ExtractNested-Result-Term$5=1 (9549) -> 805 (0.00 %, += 99.52 %) rel step time 0.00 : ParseSentence:1$1=1\1 (7106) -> 801 (0.00 %, += 99.52 %) rel step time 0.00 : ParseSentence:1$1=1 (7106) -> 801 (0.00 %, += 99.53 %) rel step time 0.00 : DoParseBlock$3=1 (7106) -> 801 (0.00 %, += 99.53 %) rel step time 0.00 ParseSentence:1$1=1\1 (7106) -> 801 (0.00 %, += 99.53 %) rel step time 0.00 ParseSentence:1$1=1 (7106) -> 801 (0.00 %, += 99.54 %) rel step time 0.00 DoParseBlock$3=1 (7106) -> 801 (0.00 %, += 99.54 %) rel step time 0.00 ValidBodyForSpec-CheckSentensePattern$1:1$1=1\1$1?1 (8897) -> 759 (0.00 %, += 99.54 %) rel step time 0.00 ValidBodyForSpec-CheckSentensePattern$1:1$1=1\1 (8897) -> 759 (0.00 %, += 99.55 %) rel step time 0.00 IsSpecStaticVar:1 (8897) -> 759 (0.00 %, += 99.55 %) rel step time 0.00 IsSpecStaticVar (8897) -> 759 (0.00 %, += 99.55 %) rel step time 0.00 FormatAssigns\1 (3532) -> 759 (0.00 %, += 99.56 %) rel step time 0.00 Apply (3532) -> 759 (0.00 %, += 99.56 %) rel step time 0.00 : ExtractNested-Result-Term$5=2\1$2\1 (9549) -> 741 (0.00 %, += 99.56 %) rel step time 0.00 ExtractNested-Result-Term$5=2\1$2\1 (9549) -> 741 (0.00 %, += 99.57 %) rel step time 0.00 Unit:1$2?1 (6799) -> 724 (0.00 %, += 99.57 %) rel step time 0.00 DoCheckExceedBlocks (6799) -> 687 (0.00 %, += 99.57 %) rel step time 0.00 Substitute (3931) -> 679 (0.00 %, += 99.58 %) rel step time 0.00 Numb-Aux (0871) -> 674 (0.00 %, += 99.58 %) rel step time 0.00 Numb (0000) -> 674 (0.00 %, += 99.58 %) rel step time 0.00 DoScan-Number (8317) -> 669 (0.00 %, += 99.59 %) rel step time 0.00 CheckExceedBlocks (6799) -> 666 (0.00 %, += 99.59 %) rel step time 0.00 DoScan-Number$1?1 (8317) -> 661 (0.00 %, += 99.59 %) rel step time 18.33 ExtractVariables-Tail (3931) -> 660 (0.00 %, += 99.59 %) rel step time 0.00 BytesFromStringList\1\1 (4746) -> 638 (0.00 %, += 99.60 %) rel step time 0.00 BytesFromStringList\1 (4746) -> 638 (0.00 %, += 99.60 %) rel step time 0.00 : DoCheckAssignments$2=3 (8897) -> 596 (0.00 %, += 99.60 %) rel step time 0.00 : DoCheckAssignments$2=2 (8897) -> 596 (0.00 %, += 99.61 %) rel step time 0.00 : DoCheckAssignments$2=1 (8897) -> 596 (0.00 %, += 99.61 %) rel step time 0.00 : RemovePos-Body$1\1\1=1 (9549) -> 596 (0.00 %, += 99.61 %) rel step time 0.00 : NameNestedAssigns\1=2 (9549) -> 596 (0.00 %, += 99.61 %) rel step time 0.00 : NameNestedAssigns\1=1 (9549) -> 596 (0.00 %, += 99.62 %) rel step time 0.00 : NameNestedAssigns\1 (9549) -> 596 (0.00 %, += 99.62 %) rel step time 0.00 : EnumerateVars-Assignments$2=5 (9549) -> 596 (0.00 %, += 99.62 %) rel step time 0.00 : EnumerateVars-Assignments$2=4 (9549) -> 596 (0.00 %, += 99.62 %) rel step time 0.00 : EnumerateVars-Assignments$2=3 (9549) -> 596 (0.00 %, += 99.63 %) rel step time 0.00 : EnumerateVars-Assignments$2=2 (9549) -> 596 (0.00 %, += 99.63 %) rel step time 0.00 : EnumerateVars-Assignments$2=1 (9549) -> 596 (0.00 %, += 99.63 %) rel step time 0.00 : PatchASTSrcPos-Chain\1 (5703) -> 596 (0.00 %, += 99.64 %) rel step time 0.00 DoCheckAssignments$2=3 (8897) -> 596 (0.00 %, += 99.64 %) rel step time 0.00 DoCheckAssignments$2=2 (8897) -> 596 (0.00 %, += 99.64 %) rel step time 0.00 DoCheckAssignments$2=1 (8897) -> 596 (0.00 %, += 99.64 %) rel step time 0.00 RemovePos-Body$1\1\1=1 (9549) -> 596 (0.00 %, += 99.65 %) rel step time 0.00 RemovePos-Body$1\1\1 (9549) -> 596 (0.00 %, += 99.65 %) rel step time 0.00 NameNestedAssigns\1=2 (9549) -> 596 (0.00 %, += 99.65 %) rel step time 0.00 NameNestedAssigns\1=1 (9549) -> 596 (0.00 %, += 99.65 %) rel step time 0.00 NameNestedAssigns\1 (9549) -> 596 (0.00 %, += 99.66 %) rel step time 0.00 EnumerateVars-Assignments$2=5 (9549) -> 596 (0.00 %, += 99.66 %) rel step time 0.00 EnumerateVars-Assignments$2=4 (9549) -> 596 (0.00 %, += 99.66 %) rel step time 0.00 EnumerateVars-Assignments$2=3 (9549) -> 596 (0.00 %, += 99.66 %) rel step time 0.00 EnumerateVars-Assignments$2=2 (9549) -> 596 (0.00 %, += 99.67 %) rel step time 0.00 EnumerateVars-Assignments$2=1 (9549) -> 596 (0.00 %, += 99.67 %) rel step time 0.00 ChainTypeMarker (9549) -> 596 (0.00 %, += 99.67 %) rel step time 0.00 PatchASTSrcPos-Chain\1 (5703) -> 596 (0.00 %, += 99.68 %) rel step time 0.00 IsHardPattern (8897) -> 594 (0.00 %, += 99.68 %) rel step time 0.00 FindVariables\1 (8897) -> 594 (0.00 %, += 99.68 %) rel step time 0.00 : SentenceTail$1=3 (6799) -> 576 (0.00 %, += 99.68 %) rel step time 0.00 : SentenceTail$1=2 (6799) -> 576 (0.00 %, += 99.69 %) rel step time 0.00 : SentenceTail$1=1 (6799) -> 576 (0.00 %, += 99.69 %) rel step time 0.00 SentenceTail$1=3 (6799) -> 576 (0.00 %, += 99.69 %) rel step time 0.00 SentenceTail$1=2 (6799) -> 576 (0.00 %, += 99.69 %) rel step time 0.00 SentenceTail$1=1 (6799) -> 576 (0.00 %, += 99.70 %) rel step time 0.00 AssignmentError (6799) -> 576 (0.00 %, += 99.70 %) rel step time 0.00 RemoveAssigns-WindBlocks\1 (9549) -> 542 (0.00 %, += 99.70 %) rel step time 0.00 : ExtractNested-Result-Term$5=2\1$2=1 (9549) -> 541 (0.00 %, += 99.70 %) rel step time 0.00 ExtractNested-Result-Term$5=2\1$2=1 (9549) -> 541 (0.00 %, += 99.71 %) rel step time 0.00 CreateContext (9549) -> 541 (0.00 %, += 99.71 %) rel step time 0.00 CombineResults (3532) -> 528 (0.00 %, += 99.71 %) rel step time 0.00 NewVarName (0000) -> 521 (0.00 %, += 99.71 %) rel step time 0.00 DoScan$22?1 (8317) -> 506 (0.00 %, += 99.72 %) rel step time 0.00 SetScopeIDs-Patch (2191) -> 497 (0.00 %, += 99.72 %) rel step time 0.00 Solve-TermEquation$5?1 (3532) -> 495 (0.00 %, += 99.72 %) rel step time 0.00 Solve-TermEquation$4?1 (3532) -> 495 (0.00 %, += 99.72 %) rel step time 0.00 Solve-TermEquation$3?1 (3532) -> 495 (0.00 %, += 99.72 %) rel step time 0.00 Solve-TermEquation$2?1 (3532) -> 495 (0.00 %, += 99.73 %) rel step time 0.00 Solve-TermEquation (3532) -> 495 (0.00 %, += 99.73 %) rel step time 0.00 Solve-Aux$1?2 (3532) -> 495 (0.00 %, += 99.73 %) rel step time 0.00 Solve-Aux$1:1 (3532) -> 495 (0.00 %, += 99.73 %) rel step time 0.00 IsSVarSubset (3532) -> 495 (0.00 %, += 99.74 %) rel step time 0.00 NewVarNameSuf (0000) -> 491 (0.00 %, += 99.74 %) rel step time 0.00 FixupKnownVars (8897) -> 482 (0.00 %, += 99.74 %) rel step time 0.00 DoTokenChain-AddRedefinition (6799) -> 445 (0.00 %, += 99.74 %) rel step time 0.00 ConflictSpecDriveErrorForName$1?1 (8897) -> 444 (0.00 %, += 99.74 %) rel step time 0.00 ConflictSpecDriveErrorForName (8897) -> 444 (0.00 %, += 99.75 %) rel step time 0.00 CheckSpecsAndDrives=2\1 (8897) -> 444 (0.00 %, += 99.75 %) rel step time 0.00 ExtractVariables (0000) -> 432 (0.00 %, += 99.75 %) rel step time 0.00 : DoGenSubst$36=3 (0177) -> 406 (0.00 %, += 99.75 %) rel step time 0.00 : DoGenSubst$36=2 (0177) -> 406 (0.00 %, += 99.75 %) rel step time 0.00 : DoGenSubst$36=1 (0177) -> 406 (0.00 %, += 99.76 %) rel step time 0.00 : OutlineConstants-OneCommand$10=1 (2191) -> 406 (0.00 %, += 99.76 %) rel step time 0.00 : ConvertOneInterpretCommand$2=1 (8023) -> 406 (0.00 %, += 99.76 %) rel step time 0.00 DoGenSubst$36=3 (0177) -> 406 (0.00 %, += 99.76 %) rel step time 0.00 DoGenSubst$36=2 (0177) -> 406 (0.00 %, += 99.76 %) rel step time 0.00 DoGenSubst$36=1 (0177) -> 406 (0.00 %, += 99.76 %) rel step time 0.00 OutlineConstants-OneCommand$10=1 (2191) -> 406 (0.00 %, += 99.77 %) rel step time 0.00 MapAccum (7236) -> 406 (0.00 %, += 99.77 %) rel step time 0.00 MakeSavers=1 (7236) -> 406 (0.00 %, += 99.77 %) rel step time 0.00 ConvertOneInterpretCommand$2=1 (8023) -> 406 (0.00 %, += 99.77 %) rel step time 0.00 MakeSavers (0000) -> 406 (0.00 %, += 99.77 %) rel step time 0.00 : Unit:1$2:1 (6799) -> 391 (0.00 %, += 99.78 %) rel step time 0.00 Unit:1$2:1 (6799) -> 391 (0.00 %, += 99.78 %) rel step time 0.00 TokName (6799) -> 391 (0.00 %, += 99.78 %) rel step time 0.00 Pipe (9549) -> 360 (0.00 %, += 99.78 %) rel step time 0.00 ExistFile (0000) -> 352 (0.00 %, += 99.78 %) rel step time 66.70 Pass-AttachEntries$7?1 (9549) -> 351 (0.00 %, += 99.78 %) rel step time 0.00 BindBrackets (3931) -> 344 (0.00 %, += 99.79 %) rel step time 0.00 DoScan-Compound (8317) -> 342 (0.00 %, += 99.79 %) rel step time 0.00 Solve-Aux$4?1 (3532) -> 330 (0.00 %, += 99.79 %) rel step time 0.00 Solve-Aux$3?1 (3532) -> 330 (0.00 %, += 99.79 %) rel step time 0.00 Solve-Aux$2?1 (3532) -> 330 (0.00 %, += 99.79 %) rel step time 0.00 : Pipe$2\1 (9549) -> 324 (0.00 %, += 99.79 %) rel step time 0.00 Pipe$2\1 (9549) -> 324 (0.00 %, += 99.79 %) rel step time 0.00 CleanupValidDriveInline (8897) -> 315 (0.00 %, += 99.80 %) rel step time 1168.51 : SimpleEscapedChar:1 (8317) -> 303 (0.00 %, += 99.80 %) rel step time 0.00 SimpleEscapedChar:1 (8317) -> 303 (0.00 %, += 99.80 %) rel step time 0.00 SimpleEscapedChar (8317) -> 303 (0.00 %, += 99.80 %) rel step time 0.00 ScanEscape$1?1 (8317) -> 303 (0.00 %, += 99.80 %) rel step time 0.00 ScanEscape (8317) -> 303 (0.00 %, += 99.80 %) rel step time 0.00 DoScan-Chars-Escape?1 (8317) -> 303 (0.00 %, += 99.80 %) rel step time 0.00 DoScan-Chars-Escape (8317) -> 303 (0.00 %, += 99.81 %) rel step time 0.00 Map (0000) -> 284 (0.00 %, += 99.81 %) rel step time 0.00 CleanupValidDriveInline$2?1 (8897) -> 270 (0.00 %, += 99.81 %) rel step time 0.00 : ValidBodyForSpec-CheckSentensePattern$1:1$1=1\1$1:1$1:1 (8897) -> 264 (0.00 %, += 99.81 %) rel step time 0.00 : ValidBodyForSpec-CheckSentensePattern$1:1$1=1\1$1:1 (8897) -> 264 (0.00 %, += 99.81 %) rel step time 0.00 : ValidBodyForSpec-CheckSentensePattern$1:1 (8897) -> 264 (0.00 %, += 99.81 %) rel step time 0.00 ValidBodyForSpec-CheckSentensePattern$1:1$1=1\1$1:1$1:1 (8897) -> 264 (0.00 %, += 99.81 %) rel step time 0.00 ValidBodyForSpec-CheckSentensePattern$1:1$1=1\1$1:1 (8897) -> 264 (0.00 %, += 99.81 %) rel step time 0.00 ValidBodyForSpec-CheckSentensePattern$1:1$1=1 (8897) -> 264 (0.00 %, += 99.82 %) rel step time 0.00 ValidBodyForSpec-CheckSentensePattern$1:1 (8897) -> 264 (0.00 %, += 99.82 %) rel step time 0.00 ValidBodyForSpec-CheckSentensePattern (8897) -> 264 (0.00 %, += 99.82 %) rel step time 0.00 FindVariables (8897) -> 264 (0.00 %, += 99.82 %) rel step time 0.00 CheckSpec-VarTypeMatching (8897) -> 264 (0.00 %, += 99.82 %) rel step time 0.00 CheckSpec-RepeatingVarInStaticParams (8897) -> 264 (0.00 %, += 99.82 %) rel step time 0.00 Solve:1 (3532) -> 264 (0.00 %, += 99.82 %) rel step time 0.00 Solution-PostProcess$8?1 (3532) -> 264 (0.00 %, += 99.82 %) rel step time 0.00 Solution-PostProcess$7?1 (3532) -> 264 (0.00 %, += 99.82 %) rel step time 0.00 Solution-PostProcess$6?1 (3532) -> 264 (0.00 %, += 99.83 %) rel step time 0.00 Solution-PostProcess$5?1 (3532) -> 264 (0.00 %, += 99.83 %) rel step time 0.00 Solution-PostProcess$4?1 (3532) -> 264 (0.00 %, += 99.83 %) rel step time 0.00 Solution-PostProcess$3?1 (3532) -> 264 (0.00 %, += 99.83 %) rel step time 0.00 Solution-PostProcess$2?1 (3532) -> 264 (0.00 %, += 99.83 %) rel step time 0.00 Solution-PostProcess$1?1 (3532) -> 264 (0.00 %, += 99.83 %) rel step time 0.00 Solution-PostProcess (3532) -> 264 (0.00 %, += 99.83 %) rel step time 0.00 GenericMatch:1 (3532) -> 264 (0.00 %, += 99.83 %) rel step time 0.00 FormatAssigns (3532) -> 264 (0.00 %, += 99.84 %) rel step time 0.00 Solve (0000) -> 264 (0.00 %, += 99.84 %) rel step time 0.00 GenericMatch (0000) -> 264 (0.00 %, += 99.84 %) rel step time 0.00 : EnumerateVars-Expr\1$8\1 (9549) -> 263 (0.00 %, += 99.84 %) rel step time 0.00 EnumerateVars-Expr\1$8\1 (9549) -> 263 (0.00 %, += 99.84 %) rel step time 0.00 : DoTokenChain:1$12=3 (6799) -> 255 (0.00 %, += 99.84 %) rel step time 0.00 : DoTokenChain:1$12=2 (6799) -> 255 (0.00 %, += 99.84 %) rel step time 0.00 DoTokenChain:1$12?1 (6799) -> 255 (0.00 %, += 99.84 %) rel step time 0.00 DoTokenChain:1$12=3 (6799) -> 255 (0.00 %, += 99.85 %) rel step time 0.00 DoTokenChain:1$12=2 (6799) -> 255 (0.00 %, += 99.85 %) rel step time 0.00 Pipe (8897) -> 252 (0.00 %, += 99.85 %) rel step time 0.00 Fetch (8897) -> 252 (0.00 %, += 99.85 %) rel step time 0.00 IsHardTerm (8897) -> 231 (0.00 %, += 99.85 %) rel step time 0.00 IsHardPattern$8?1 (8897) -> 231 (0.00 %, += 99.85 %) rel step time 0.00 : Pipe$2\1 (8897) -> 216 (0.00 %, += 99.85 %) rel step time 0.00 Pipe$2\1 (8897) -> 216 (0.00 %, += 99.85 %) rel step time 0.00 DirectorySeparator=1 (0871) -> 214 (0.00 %, += 99.85 %) rel step time 0.00 DS (8133) -> 214 (0.00 %, += 99.85 %) rel step time 0.00 DirectorySeparator (0000) -> 214 (0.00 %, += 99.86 %) rel step time 0.00 MakeContinuationSentence (3931) -> 212 (0.00 %, += 99.86 %) rel step time 0.00 ExistFile-T (8133) -> 208 (0.00 %, += 99.86 %) rel step time 0.00 TkInline (6799) -> 206 (0.00 %, += 99.86 %) rel step time 0.00 UnBracket (5703) -> 204 (0.00 %, += 99.86 %) rel step time 0.00 CheckSpecs-Functions (8897) -> 201 (0.00 %, += 99.86 %) rel step time 0.00 Apply (4171) -> 180 (0.00 %, += 99.86 %) rel step time 0.00 : DoScan-Number$1:1 (8317) -> 175 (0.00 %, += 99.86 %) rel step time 0.00 DoScan-Number$1:1 (8317) -> 175 (0.00 %, += 99.86 %) rel step time 0.00 ResolveIncludes (5703) -> 174 (0.00 %, += 99.86 %) rel step time 0.00 : RemoveAssigns-Conditions\1=1 (9549) -> 168 (0.00 %, += 99.86 %) rel step time 0.00 : ExtractNested-Conditions$1=4 (9549) -> 168 (0.00 %, += 99.86 %) rel step time 0.00 : ExtractNested-Conditions$1=3 (9549) -> 168 (0.00 %, += 99.87 %) rel step time 0.00 : ExtractNested-Conditions$1=2 (9549) -> 168 (0.00 %, += 99.87 %) rel step time 0.00 : ExtractNested-Conditions$1=1 (9549) -> 168 (0.00 %, += 99.87 %) rel step time 0.00 UnConditionSentence?2 (3931) -> 168 (0.00 %, += 99.87 %) rel step time 0.00 UnConditionSentence?1 (3931) -> 168 (0.00 %, += 99.87 %) rel step time 0.00 UnConditionSentence (3931) -> 168 (0.00 %, += 99.87 %) rel step time 0.00 T0 (3931) -> 168 (0.00 %, += 99.87 %) rel step time 0.00 PatternSteps (3931) -> 168 (0.00 %, += 99.87 %) rel step time 0.00 OpenEChain (3931) -> 168 (0.00 %, += 99.87 %) rel step time 0.00 DoUnCondition$1?2 (3931) -> 168 (0.00 %, += 99.87 %) rel step time 0.00 ConditionSubFunctions?7 (3931) -> 168 (0.00 %, += 99.87 %) rel step time 0.00 ConditionSubFunctions?6 (3931) -> 168 (0.00 %, += 99.87 %) rel step time 0.00 ConditionSubFunctions?5 (3931) -> 168 (0.00 %, += 99.87 %) rel step time 0.00 ConditionSubFunctions?4 (3931) -> 168 (0.00 %, += 99.88 %) rel step time 0.00 ConditionSubFunctions?3 (3931) -> 168 (0.00 %, += 99.88 %) rel step time 0.00 ConditionSubFunctions?2 (3931) -> 168 (0.00 %, += 99.88 %) rel step time 0.00 ConditionSubFunctions?1 (3931) -> 168 (0.00 %, += 99.88 %) rel step time 0.00 ConditionSubFunctions (3931) -> 168 (0.00 %, += 99.88 %) rel step time 0.00 ConditionContext (3931) -> 168 (0.00 %, += 99.88 %) rel step time 0.00 AnalyzeContinuation (3931) -> 168 (0.00 %, += 99.88 %) rel step time 0.00 RemoveAssigns-Conditions\1=1 (9549) -> 168 (0.00 %, += 99.88 %) rel step time 0.00 RemoveAssigns-Conditions\1 (9549) -> 168 (0.00 %, += 99.88 %) rel step time 0.00 ExtractNested-Conditions$1=4 (9549) -> 168 (0.00 %, += 99.88 %) rel step time 0.00 ExtractNested-Conditions$1=3 (9549) -> 168 (0.00 %, += 99.88 %) rel step time 0.00 ExtractNested-Conditions$1=2 (9549) -> 168 (0.00 %, += 99.88 %) rel step time 0.00 ExtractNested-Conditions$1=1 (9549) -> 168 (0.00 %, += 99.88 %) rel step time 0.00 : CheckSpecs-Functions$2=1:1 (8897) -> 165 (0.00 %, += 99.89 %) rel step time 0.00 : CheckSpecs-Formats\1$1:1$1:1 (8897) -> 165 (0.00 %, += 99.89 %) rel step time 0.00 : CheckSpecs-Formats\1$1:1 (8897) -> 165 (0.00 %, += 99.89 %) rel step time 0.00 : Pass-EnumerateVariables\1$1=1 (9549) -> 165 (0.00 %, += 99.89 %) rel step time 0.00 : Specialization$1=2 (6799) -> 165 (0.00 %, += 99.89 %) rel step time 0.00 : Specialization$1=1 (6799) -> 165 (0.00 %, += 99.89 %) rel step time 0.00 ValidBodyForSpec$1=1 (8897) -> 165 (0.00 %, += 99.89 %) rel step time 0.00 ValidBodyForSpec (8897) -> 165 (0.00 %, += 99.89 %) rel step time 0.00 ErrorsForRepeatedVariables (8897) -> 165 (0.00 %, += 99.89 %) rel step time 0.00 ConflictSpecDriveErrorForName$1?2 (8897) -> 165 (0.00 %, += 99.89 %) rel step time 0.00 CheckSpecs-Functions$2=1:1 (8897) -> 165 (0.00 %, += 99.89 %) rel step time 0.00 CheckSpecs-Functions$2=1 (8897) -> 165 (0.00 %, += 99.89 %) rel step time 0.00 CheckSpecs-Formats\1$1:1$1:1 (8897) -> 165 (0.00 %, += 99.89 %) rel step time 0.00 CheckSpecs-Formats\1$1:1 (8897) -> 165 (0.00 %, += 99.89 %) rel step time 68.85 CheckRepeatedVariables (8897) -> 165 (0.00 %, += 99.90 %) rel step time 0.00 Pass-EnumerateVariables\1$1=1 (9549) -> 165 (0.00 %, += 99.90 %) rel step time 0.00 Specialization$1=2 (6799) -> 165 (0.00 %, += 99.90 %) rel step time 0.00 Specialization$1=1 (6799) -> 165 (0.00 %, += 99.90 %) rel step time 0.00 Specialization (6799) -> 165 (0.00 %, += 99.90 %) rel step time 0.00 Expect (6799) -> 165 (0.00 %, += 99.90 %) rel step time 0.00 : AnalyzeFile$1:1 (8133) -> 144 (0.00 %, += 99.90 %) rel step time 0.00 AnalyzeFile$1?1 (8133) -> 144 (0.00 %, += 99.90 %) rel step time 0.00 AnalyzeFile$1:1 (8133) -> 144 (0.00 %, += 99.90 %) rel step time 0.00 AnalyzeFile (8133) -> 144 (0.00 %, += 99.90 %) rel step time 0.00 : Open-Auto=1 (0871) -> 143 (0.00 %, += 99.90 %) rel step time 0.00 Open-Auto=1 (0871) -> 143 (0.00 %, += 99.90 %) rel step time 0.00 GetFreeHandle (0871) -> 143 (0.00 %, += 99.90 %) rel step time 0.00 Open-Auto (0000) -> 143 (0.00 %, += 99.90 %) rel step time 0.00 Open (0000) -> 143 (0.00 %, += 99.91 %) rel step time 164.18 Close (0000) -> 143 (0.00 %, += 99.91 %) rel step time 1911.95 EL-Create (0000) -> 142 (0.00 %, += 99.91 %) rel step time 0.00 : DoTokenChain:1$11=1:1 (6799) -> 140 (0.00 %, += 99.91 %) rel step time 0.00 : DoTokenChain:1$11=1 (6799) -> 140 (0.00 %, += 99.91 %) rel step time 0.00 DoTokenChain:1$11=1:1 (6799) -> 140 (0.00 %, += 99.91 %) rel step time 0.00 DoTokenChain:1$11=1 (6799) -> 140 (0.00 %, += 99.91 %) rel step time 0.00 AnalyzeInclude (8133) -> 140 (0.00 %, += 99.91 %) rel step time 0.00 ResolveIncludes-CheckAlias (5703) -> 138 (0.00 %, += 99.91 %) rel step time 0.00 Function$1?1 (6799) -> 135 (0.00 %, += 99.91 %) rel step time 0.00 Solve-TermEquation$9?1 (3532) -> 132 (0.00 %, += 99.91 %) rel step time 0.00 Solve-TermEquation$8?1 (3532) -> 132 (0.00 %, += 99.91 %) rel step time 0.00 Solve-TermEquation$6?1 (3532) -> 132 (0.00 %, += 99.91 %) rel step time 0.00 PassiveHoles (3931) -> 132 (0.00 %, += 99.91 %) rel step time 0.00 AnalyzeContinuation$2?1 (3931) -> 132 (0.00 %, += 99.91 %) rel step time 0.00 : EnumerateVars-Expr\1$6\1 (9549) -> 126 (0.00 %, += 99.91 %) rel step time 0.00 : DoTokenChain:1$9=3:1 (6799) -> 126 (0.00 %, += 99.92 %) rel step time 0.00 : DoTokenChain:1$9=3 (6799) -> 126 (0.00 %, += 99.92 %) rel step time 0.00 : DoTokenChain:1$9=2 (6799) -> 126 (0.00 %, += 99.92 %) rel step time 0.00 : DoTokenChain:1$9=1 (6799) -> 126 (0.00 %, += 99.92 %) rel step time 0.00 DoScan$20?1 (8317) -> 126 (0.00 %, += 99.92 %) rel step time 0.00 EnumerateVars-Expr\1$6\1 (9549) -> 126 (0.00 %, += 99.92 %) rel step time 0.00 DoTokenChain:1$9=3:1 (6799) -> 126 (0.00 %, += 99.92 %) rel step time 0.00 DoTokenChain:1$9=3 (6799) -> 126 (0.00 %, += 99.92 %) rel step time 0.00 DoTokenChain:1$9=2 (6799) -> 126 (0.00 %, += 99.92 %) rel step time 0.00 DoTokenChain:1$9=1 (6799) -> 126 (0.00 %, += 99.92 %) rel step time 0.00 OpenEChain$2?1 (3931) -> 120 (0.00 %, += 99.92 %) rel step time 0.00 : CheckBlocks\1 (8897) -> 114 (0.00 %, += 99.92 %) rel step time 0.00 : NameNestedBlocks=1\1=1 (9549) -> 114 (0.00 %, += 99.92 %) rel step time 0.00 : NameNestedBlocks=1\1 (9549) -> 114 (0.00 %, += 99.92 %) rel step time 0.00 : EnumerateVars-Blocks\1 (9549) -> 114 (0.00 %, += 99.92 %) rel step time 99.65 : PatchASTSrcPos-Blocks\1 (5703) -> 114 (0.00 %, += 99.92 %) rel step time 0.00 CheckBlocks\1 (8897) -> 114 (0.00 %, += 99.92 %) rel step time 0.00 RemovePos-Blocks\1 (9549) -> 114 (0.00 %, += 99.92 %) rel step time 0.00 NameNestedBlocks=1\1=1 (9549) -> 114 (0.00 %, += 99.92 %) rel step time 0.00 NameNestedBlocks=1\1 (9549) -> 114 (0.00 %, += 99.93 %) rel step time 0.00 EnumerateVars-Blocks\1 (9549) -> 114 (0.00 %, += 99.93 %) rel step time 0.00 PatchASTSrcPos-Blocks\1 (5703) -> 114 (0.00 %, += 99.93 %) rel step time 0.00 : Result-Blocks$3=1 (6799) -> 111 (0.00 %, += 99.93 %) rel step time 0.00 Result-Blocks$3=1 (6799) -> 111 (0.00 %, += 99.93 %) rel step time 0.00 Map (4171) -> 111 (0.00 %, += 99.93 %) rel step time 109.17 G_LogFile (2711) -> 110 (0.00 %, += 99.93 %) rel step time 0.00 LogHandle:1 (2711) -> 109 (0.00 %, += 99.93 %) rel step time 0.00 LogHandle (2711) -> 109 (0.00 %, += 99.93 %) rel step time 0.00 LoadFile=1 (2369) -> 107 (0.00 %, += 99.93 %) rel step time 0.00 LoadFile (0000) -> 107 (0.00 %, += 99.93 %) rel step time 0.00 : PatchASTSrcPos=1 (5703) -> 106 (0.00 %, += 99.93 %) rel step time 0.00 ResolveIncludes$3\1 (5703) -> 106 (0.00 %, += 99.93 %) rel step time 0.00 PatchASTSrcPos=1 (5703) -> 106 (0.00 %, += 99.93 %) rel step time 0.00 PatchASTSrcPos (5703) -> 106 (0.00 %, += 99.93 %) rel step time 0.00 LoadAST (5703) -> 106 (0.00 %, += 99.93 %) rel step time 0.00 GlueErrorListsAndASTs\1 (5703) -> 106 (0.00 %, += 99.93 %) rel step time 0.00 DisplayLexerWarnings (5703) -> 106 (0.00 %, += 99.93 %) rel step time 0.00 ExpandREF5RSL (8133) -> 106 (0.00 %, += 99.93 %) rel step time 0.00 EL-Concat (0000) -> 106 (0.00 %, += 99.93 %) rel step time 0.00 : LoadAST$1=4 (5703) -> 104 (0.00 %, += 99.94 %) rel step time 0.00 : LoadAST$1=3 (5703) -> 104 (0.00 %, += 99.94 %) rel step time 0.00 : LoadAST$1=2 (5703) -> 104 (0.00 %, += 99.94 %) rel step time 0.00 : LoadAST$1=1 (5703) -> 104 (0.00 %, += 99.94 %) rel step time 0.00 Skip-UTF-8-Bom (8317) -> 104 (0.00 %, += 99.94 %) rel step time 0.00 Program (6799) -> 104 (0.00 %, += 99.94 %) rel step time 0.00 PreludeIncludeLine:1 (5703) -> 104 (0.00 %, += 99.94 %) rel step time 0.00 PreludeIncludeLine (5703) -> 104 (0.00 %, += 99.94 %) rel step time 0.00 LoadAST$1=4 (5703) -> 104 (0.00 %, += 99.94 %) rel step time 0.00 LoadAST$1=3 (5703) -> 104 (0.00 %, += 99.94 %) rel step time 0.00 LoadAST$1=2 (5703) -> 104 (0.00 %, += 99.94 %) rel step time 0.00 LoadAST$1=1 (5703) -> 104 (0.00 %, += 99.94 %) rel step time 0.00 R5-Scan (0000) -> 104 (0.00 %, += 99.94 %) rel step time 0.00 R5-Parse (0000) -> 104 (0.00 %, += 99.94 %) rel step time 0.00 Config-GetR5Mode (0000) -> 104 (0.00 %, += 99.94 %) rel step time 0.00 Config-GetPrelude (0000) -> 104 (0.00 %, += 99.94 %) rel step time 0.00 : IsHardPattern$4:1 (8897) -> 99 (0.00 %, += 99.94 %) rel step time 0.00 IsHardPattern$4:1 (8897) -> 99 (0.00 %, += 99.94 %) rel step time 0.00 : CheckSentenceEnd$1=2 (6799) -> 90 (0.00 %, += 99.94 %) rel step time 0.00 : CheckSentenceEnd$1=1 (6799) -> 90 (0.00 %, += 99.94 %) rel step time 0.00 CheckSentenceEnd$1=2 (6799) -> 90 (0.00 %, += 99.94 %) rel step time 0.00 CheckSentenceEnd$1=1 (6799) -> 90 (0.00 %, += 99.94 %) rel step time 0.00 CheckMistakenAssign (6799) -> 90 (0.00 %, += 99.95 %) rel step time 0.00 : OutlineConstants-OneCommand$4=1 (2191) -> 89 (0.00 %, += 99.95 %) rel step time 0.00 OutlineConstants-OneCommand$4=1 (2191) -> 89 (0.00 %, += 99.95 %) rel step time 0.00 Apply (7045) -> 85 (0.00 %, += 99.95 %) rel step time 0.00 ParseBlock (7106) -> 83 (0.00 %, += 99.95 %) rel step time 0.00 ParseElements (7106) -> 81 (0.00 %, += 99.95 %) rel step time 0.00 DoMapAccum (7045) -> 78 (0.00 %, += 99.95 %) rel step time 0.00 DoMapAccum-Aux (7045) -> 76 (0.00 %, += 99.95 %) rel step time 0.00 TkEnum (6799) -> 74 (0.00 %, += 99.95 %) rel step time 0.00 TkDrive (6799) -> 73 (0.00 %, += 99.95 %) rel step time 0.00 GetBaseName (4171) -> 73 (0.00 %, += 99.95 %) rel step time 0.00 : Log-AST:1 (2711) -> 72 (0.00 %, += 99.95 %) rel step time 0.00 : ParseFunction$1=1 (7106) -> 72 (0.00 %, += 99.95 %) rel step time 0.00 TkMeta (6799) -> 72 (0.00 %, += 99.95 %) rel step time 0.00 Log-AST:1 (2711) -> 72 (0.00 %, += 99.95 %) rel step time 0.00 MapAccum (5703) -> 72 (0.00 %, += 99.95 %) rel step time 0.00 AnalyzeInclude$1:1 (8133) -> 72 (0.00 %, += 99.95 %) rel step time 0.00 AnalyzeFile-CheckNotFound (8133) -> 72 (0.00 %, += 99.95 %) rel step time 0.00 DoMapAccum (4171) -> 72 (0.00 %, += 99.95 %) rel step time 0.00 ParseFunction$1=1 (7106) -> 72 (0.00 %, += 99.95 %) rel step time 0.00 ParseFunction (7106) -> 72 (0.00 %, += 99.95 %) rel step time 0.00 Log-AST (0000) -> 72 (0.00 %, += 99.95 %) rel step time 0.00 Config-GetSearchFolders (0000) -> 71 (0.00 %, += 99.95 %) rel step time 0.00 : FindInclude\1 (8133) -> 70 (0.00 %, += 99.95 %) rel step time 0.00 ResolveIncludes-CheckValid (5703) -> 70 (0.00 %, += 99.95 %) rel step time 0.00 ResolveIncludes-CheckEqualAST (5703) -> 70 (0.00 %, += 99.95 %) rel step time 0.00 FindInclude\1 (8133) -> 70 (0.00 %, += 99.95 %) rel step time 0.00 FindInclude:1 (8133) -> 70 (0.00 %, += 99.95 %) rel step time 0.00 FindInclude (0000) -> 70 (0.00 %, += 99.95 %) rel step time 0.00 : ParseElements$4=1 (7106) -> 69 (0.00 %, += 99.95 %) rel step time 0.00 ParseElements$4=1 (7106) -> 69 (0.00 %, += 99.96 %) rel step time 0.00 AnalyzeInclude$3:1 (8133) -> 68 (0.00 %, += 99.96 %) rel step time 0.00 DirectiveTail (3398) -> 66 (0.00 %, += 99.96 %) rel step time 0.00 StringLiteral-Escape (3398) -> 61 (0.00 %, += 99.96 %) rel step time 0.00 : DoTokenChain:1$7=1 (6799) -> 56 (0.00 %, += 99.96 %) rel step time 0.00 DoScan$14?1 (8317) -> 56 (0.00 %, += 99.96 %) rel step time 0.00 DoTokenChain:1$7=1 (6799) -> 56 (0.00 %, += 99.96 %) rel step time 0.00 Mul (0000) -> 56 (0.00 %, += 99.96 %) rel step time 0.00 T5-sub?2 (3931) -> 44 (0.00 %, += 99.96 %) rel step time 0.00 T5-sub?1 (3931) -> 44 (0.00 %, += 99.96 %) rel step time 0.00 T5-sub (3931) -> 44 (0.00 %, += 99.96 %) rel step time 0.00 StartComment (3398) -> 43 (0.00 %, += 99.96 %) rel step time 0.00 ValidDirective$10\1 (3398) -> 41 (0.00 %, += 99.96 %) rel step time 0.00 ResolveOpts (4990) -> 40 (0.00 %, += 99.96 %) rel step time 0.00 MarkupArguments (4990) -> 40 (0.00 %, += 99.96 %) rel step time 0.00 Pass-RemoveRedundantDriveInline$1?1 (9549) -> 40 (0.00 %, += 99.96 %) rel step time 889.91 CComment-CheckEnd (3398) -> 40 (0.00 %, += 99.96 %) rel step time 0.00 Inc (4990) -> 39 (0.00 %, += 99.96 %) rel step time 0.00 ParseCommandLine\2 (7045) -> 39 (0.00 %, += 99.96 %) rel step time 0.00 : NameNestedResultRec-Term$2=1 (9549) -> 37 (0.00 %, += 99.96 %) rel step time 0.00 : ExtractNested-Result-Term$3=1 (9549) -> 37 (0.00 %, += 99.96 %) rel step time 0.00 NameNestedResultRec-Term$2=1 (9549) -> 37 (0.00 %, += 99.96 %) rel step time 0.00 ExtractNested-Result-Term$3=1 (9549) -> 37 (0.00 %, += 99.96 %) rel step time 0.00 ScanCollisions (4171) -> 37 (0.00 %, += 99.96 %) rel step time 0.00 ParseCommandLine\3 (7045) -> 37 (0.00 %, += 99.96 %) rel step time 0.00 Lower (0000) -> 37 (0.00 %, += 99.96 %) rel step time 0.00 : FindErrors=3 (8897) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : FindErrors=2 (8897) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : CheckSpecsAndDrives=2 (8897) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : OutlineConstants-RASLOnly$3=1 (2191) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : LowLevelRASL=5 (2191) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : LowLevelRASL=4 (2191) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : LowLevelRASL=3 (2191) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : LowLevelRASL=2 (2191) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : LowLevelRASL=1 (2191) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : Desugar\1 (9549) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : Log-HighLevelRASL:1 (2711) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : LowLevelRASL-Native:1 (5064) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : FrontEnd=2 (5703) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : FrontEnd=1 (5703) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : CreateMetaFunctions$1=2 (5703) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : CreateMetaFunctions$1=1 (5703) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : CompileFile=1 (5703) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : CompileFile:1 (5703) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : BackEnd=9:1 (5703) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : BackEnd=9 (5703) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : BackEnd=8 (5703) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : BackEnd=7 (5703) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : BackEnd=6 (5703) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : BackEnd=5 (5703) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : BackEnd=4 (5703) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : BackEnd=3 (5703) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : BackEnd=2 (5703) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : BackEnd=12 (5703) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : BackEnd=11 (5703) -> 36 (0.00 %, += 99.96 %) rel step time 0.00 : BackEnd=10 (5703) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 : BackEnd=1 (5703) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 : BackEnd:1 (5703) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 : BackEnd-RASLModule:1 (5703) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 : FindFiles\1\1 (8133) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 : FindFiles\1 (8133) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 : FindFiles=1 (8133) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 : RenameLocations-Aux=2\1$2=1 (4171) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 : RenameLocations-Aux=2\1 (4171) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 : RenameLocations-Aux=1 (4171) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 : LookupSourceFiles\1:1 (4171) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 : LookupSourceFiles\1 (4171) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 : GetFileName$3=2 (4171) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 : GetFileName$3=1 (4171) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 : CompileFiles$1\1$3=1 (4171) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 : CompileFiles$1\1 (4171) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 : PrepareCommonRASL=2 (8023) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 : PrepareCommonRASL=1 (8023) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 : LowLevelRASL-RASL=2 (8023) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 : LowLevelRASL-RASL=1 (8023) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 : GenProgram-RASL=4 (4746) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 : GenProgram-RASL=3 (4746) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 : GenProgram-RASL=2 (4746) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 : GenProgram-RASL=1 (4746) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 : GenCommand-Globals$4=1 (4746) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 : Assemble=3 (4746) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 SeparateDefines (8897) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 FindInvalidForwards (8897) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 FindInvalidEntry (8897) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 FindInvalidDriveInline (8897) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 FindErrors=3 (8897) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 FindErrors=2 (8897) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 FindErrors=1 (8897) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 FindErrors (8897) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 CleanupValidForwards (8897) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 CleanupValidEntry (8897) -> 36 (0.00 %, += 99.97 %) rel step time 315.57 CheckSpecsAndDrives=2 (8897) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 CheckSpecsAndDrives=1 (8897) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 CheckSpecsAndDrives (8897) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 CheckSpecs=3 (8897) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 CheckSpecs=2 (8897) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 CheckSpecs=1 (8897) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 CheckSpecs-SeparateErrors-Rec (8897) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 CheckSpecs-SeparateErrors (8897) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 CheckSpecs-Redefine (8897) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 CheckSpecs-Formats (8897) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 CheckSpecs (8897) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 CheckRedefinitions (8897) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 CheckDeclarations (8897) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 SelectConstantsOutliner (2191) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 OutlineConstants-RASLOnly$3=1 (2191) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 OutlineConstants-Metatable (2191) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 LowLevelRASL=5 (2191) -> 36 (0.00 %, += 99.97 %) rel step time 2692.86 LowLevelRASL=4 (2191) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 LowLevelRASL=3 (2191) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 LowLevelRASL=2 (2191) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 LowLevelRASL=1 (2191) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 Canonize (2191) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 Pass-RemoveRedundantDriveInline (9549) -> 36 (0.00 %, += 99.97 %) rel step time 336.61 Pass-RemovePos (9549) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 Pass-RemoveBlocksAndAssigns (9549) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 Pass-NameNestedFuncs (9549) -> 36 (0.00 %, += 99.97 %) rel step time 0.00 Pass-FoldEnums (9549) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 Pass-FlatNestedFuncs (9549) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 Pass-EnumerateVariables (9549) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 Pass-CleanupRedudandExterns=1 (9549) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 Pass-CleanupRedudandExterns (9549) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 Desugar\1 (9549) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 Putout-Aux (0871) -> 36 (0.00 %, += 99.98 %) rel step time 1977.57 Log-HighLevelRASL:1 (2711) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 LowLevelRASL-Native:1 (5064) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 GlueErrorListsAndASTs (5703) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 FrontEnd=3 (5703) -> 36 (0.00 %, += 99.98 %) rel step time 2650.78 FrontEnd=2 (5703) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 FrontEnd=1 (5703) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 FrontEnd (5703) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 CreateMetaFunctions$1=2 (5703) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 CreateMetaFunctions$1=1 (5703) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 CreateMetaFunctions (5703) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 CompileFile=1 (5703) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 CompileFile:1 (5703) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 BackEnd=9:1 (5703) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 BackEnd=9 (5703) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 BackEnd=8 (5703) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 BackEnd=7 (5703) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 BackEnd=6 (5703) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 BackEnd=5 (5703) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 BackEnd=4 (5703) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 BackEnd=3 (5703) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 BackEnd=2 (5703) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 BackEnd=12 (5703) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 BackEnd=11 (5703) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 BackEnd=10 (5703) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 BackEnd=1 (5703) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 BackEnd:1 (5703) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 BackEnd-RASLModule:1 (5703) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 BackEnd-RASLModule (5703) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 BackEnd (5703) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 FindFiles\1\1 (8133) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 FindFiles\1 (8133) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 FindFiles=1:1 (8133) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 FindFiles=1 (8133) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 RenameLocations-Aux=2\1$2=1 (4171) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 RenameLocations-Aux=2\1$1?1 (4171) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 RenameLocations-Aux=2\1 (4171) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 RenameLocations-Aux=2 (4171) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 RenameLocations-Aux=1 (4171) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 RenameLocations-Aux (4171) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 RenameLocations (4171) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 MapAccum (4171) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 LookupSourceFiles\1:1 (4171) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 LookupSourceFiles\1 (4171) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 Inc (4171) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 GlueNames (4171) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 GetFileName$3=2 (4171) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 GetFileName$3=1 (4171) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 GetFileName (4171) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 GetBaseNameLowerCaseNoExt (4171) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 DoMapAccum-Aux (4171) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 CompileFiles$1\1$3=1 (4171) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 CompileFiles$1\1$3:1 (4171) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 CompileFiles$1\1 (4171) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 AddNumSuffix (4171) -> 36 (0.00 %, += 99.98 %) rel step time 0.00 SaveBytes-Handle (2369) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 SetFunctionsScopeClass=1 (8023) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 SetFunctionsScopeClass (8023) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 PrepareCommonRASL=2 (8023) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 PrepareCommonRASL=1 (8023) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 PrepareCommonRASL (8023) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 LowLevelRASL-RASL=2 (8023) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 LowLevelRASL-RASL=1 (8023) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 GlueFunctionRASLs (8023) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 MapAccum (4746) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 GenProgram-RASL=4 (4746) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 GenProgram-RASL=3 (4746) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 GenProgram-RASL=2 (4746) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 GenProgram-RASL=1 (4746) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 GenCommand-Globals$4=1 (4746) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 CalcLabelsOffsets (4746) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 BytesFromStringList (4746) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 BytesFromIdentList (4746) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 BytesFromFunctionList (4746) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 Assemble=3 (4746) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 Assemble=2 (4746) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 Assemble=1 (4746) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 Assemble (4746) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 WriteBytes (0000) -> 36 (0.00 %, += 99.99 %) rel step time 3281.92 SaveBytes (0000) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 PutBlockStart (0000) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 Prout (0000) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 Pass-UnCondition (0000) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 OptTree-CleanupMarkup (0000) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 LowLevelRASL-RASL (0000) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 LowLevelRASL-Native (0000) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 LowLevelRASL (0000) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 Log-HighLevelRASL (0000) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 HighLevelRASL (0000) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 GenProgram-RASL (0000) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 FindFiles (0000) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 EL-Destroy (0000) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 Desugar (0000) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 Config-GetOverwriteExistRasls (0000) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 Config-GetOptTree (0000) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 Config-GetOptResult (0000) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 Config-GetOptPattern (0000) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 Config-GetGenMode (0000) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 Config-GetDebugMode (0000) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 Config-GetConditionsMode (0000) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 CompileFile (0000) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 CheckProgram (0000) -> 36 (0.00 %, += 99.99 %) rel step time 0.00 MakeCompound (8317) -> 35 (0.00 %, += 99.99 %) rel step time 0.00 : Include=1 (6799) -> 32 (0.00 %, += 99.99 %) rel step time 0.00 : Include:1 (6799) -> 32 (0.00 %, += 99.99 %) rel step time 0.00 Apply (3807) -> 32 (0.00 %, += 99.99 %) rel step time 0.00 Include=1 (6799) -> 32 (0.00 %, += 99.99 %) rel step time 0.00 Include:1 (6799) -> 32 (0.00 %, += 99.99 %) rel step time 0.00 Include (6799) -> 32 (0.00 %, += 99.99 %) rel step time 0.00 EnumerateVars-Expr\1$1=1 (9549) -> 30 (0.00 %, += 99.99 %) rel step time 0.00 EasterEgg (8317) -> 28 (0.00 %, += 99.99 %) rel step time 0.00 Reduce (3807) -> 24 (0.00 %, += 99.99 %) rel step time 0.00 OctDigit (3807) -> 24 (0.00 %, += 99.99 %) rel step time 0.00 Map (3807) -> 24 (0.00 %, += 99.99 %) rel step time 0.00 DoHexDigit (8317) -> 23 (0.00 %, += 99.99 %) rel step time 0.00 T7?2 (3931) -> 22 (0.00 %, += 99.99 %) rel step time 0.00 T7?1 (3931) -> 22 (0.00 %, += 99.99 %) rel step time 0.00 T7 (3931) -> 22 (0.00 %, += 99.99 %) rel step time 0.00 T6?2 (3931) -> 22 (0.00 %, += 99.99 %) rel step time 0.00 T6?1 (3931) -> 22 (0.00 %, += 99.99 %) rel step time 0.00 T6 (3931) -> 22 (0.00 %, += 99.99 %) rel step time 0.00 T5?1 (3931) -> 22 (0.00 %, += 99.99 %) rel step time 0.00 T5-UnHole (3931) -> 22 (0.00 %, += 100.00 %) rel step time 0.00 T5 (3931) -> 22 (0.00 %, += 100.00 %) rel step time 550.81 T4?1 (3931) -> 22 (0.00 %, += 100.00 %) rel step time 0.00 T4 (3931) -> 22 (0.00 %, += 100.00 %) rel step time 0.00 T3?1 (3931) -> 22 (0.00 %, += 100.00 %) rel step time 0.00 T3 (3931) -> 22 (0.00 %, += 100.00 %) rel step time 0.00 T2?3 (3931) -> 22 (0.00 %, += 100.00 %) rel step time 0.00 T2?2 (3931) -> 22 (0.00 %, += 100.00 %) rel step time 0.00 T2?1 (3931) -> 22 (0.00 %, += 100.00 %) rel step time 0.00 T2 (3931) -> 22 (0.00 %, += 100.00 %) rel step time 0.00 T1?3 (3931) -> 22 (0.00 %, += 100.00 %) rel step time 0.00 T1?2 (3931) -> 22 (0.00 %, += 100.00 %) rel step time 0.00 T1?1 (3931) -> 22 (0.00 %, += 100.00 %) rel step time 0.00 T1 (3931) -> 22 (0.00 %, += 100.00 %) rel step time 0.00 MakeOpenE-ForwardNext?2 (3931) -> 22 (0.00 %, += 100.00 %) rel step time 0.00 MakeOpenE-ForwardNext?1 (3931) -> 22 (0.00 %, += 100.00 %) rel step time 0.00 MakeOpenE-ForwardNext (3931) -> 22 (0.00 %, += 100.00 %) rel step time 0.00 DoOpenEChain (3931) -> 22 (0.00 %, += 100.00 %) rel step time 0.00 : ParseSentence:1$2=2 (7106) -> 20 (0.00 %, += 100.00 %) rel step time 0.00 : ParseSentence:1$2=1 (7106) -> 20 (0.00 %, += 100.00 %) rel step time 0.00 DoUnEscapeString-SR (3807) -> 20 (0.00 %, += 100.00 %) rel step time 0.00 CompoundSymbol (3398) -> 20 (0.00 %, += 100.00 %) rel step time 0.00 ParseSentence:1$2=2 (7106) -> 20 (0.00 %, += 100.00 %) rel step time 0.00 ParseSentence:1$2=1 (7106) -> 20 (0.00 %, += 100.00 %) rel step time 0.00 OpenEChain$3?3 (3931) -> 19 (0.00 %, += 100.00 %) rel step time 0.00 OpenEChain$3?2 (3931) -> 19 (0.00 %, += 100.00 %) rel step time 0.00 OpenEChain$3?1 (3931) -> 19 (0.00 %, += 100.00 %) rel step time 0.00 DoOpenEChain$1?1 (3931) -> 19 (0.00 %, += 100.00 %) rel step time 0.00 : DigitFromChar:1 (3807) -> 16 (0.00 %, += 100.00 %) rel step time 0.00 : CharFromNum\1 (3807) -> 16 (0.00 %, += 100.00 %) rel step time 0.00 Mod$1=1 (0871) -> 16 (0.00 %, += 100.00 %) rel step time 0.00 Div$1=1 (0871) -> 16 (0.00 %, += 100.00 %) rel step time 0.00 DigitFromChar:1 (3807) -> 16 (0.00 %, += 100.00 %) rel step time 0.00 DigitFromChar (3807) -> 16 (0.00 %, += 100.00 %) rel step time 0.00 CharFromNum\1 (3807) -> 16 (0.00 %, += 100.00 %) rel step time 0.00 StringLiteral-Escape-Xnext (3398) -> 16 (0.00 %, += 100.00 %) rel step time 0.00 Mod (0000) -> 16 (0.00 %, += 100.00 %) rel step time 0.00 Div (0000) -> 16 (0.00 %, += 100.00 %) rel step time 0.00 DoScan$24?1 (8317) -> 12 (0.00 %, += 100.00 %) rel step time 0.00 Chr (0000) -> 12 (0.00 %, += 100.00 %) rel step time 0.00 ValidDirective (3398) -> 11 (0.00 %, += 100.00 %) rel step time 0.00 Directive (3398) -> 11 (0.00 %, += 100.00 %) rel step time 0.00 : ParseElements$2=1 (7106) -> 10 (0.00 %, += 100.00 %) rel step time 0.00 ParseElements$2=1 (7106) -> 10 (0.00 %, += 100.00 %) rel step time 0.00 ParseElement-SwDirective (7106) -> 10 (0.00 %, += 100.00 %) rel step time 0.00 CleanupValidDriveInline$1?1 (8897) -> 9 (0.00 %, += 100.00 %) rel step time 0.00 ParseNameList-Tail (7106) -> 9 (0.00 %, += 100.00 %) rel step time 0.00 ParseNameList (7106) -> 9 (0.00 %, += 100.00 %) rel step time 0.00 : HexDigit:1 (8317) -> 8 (0.00 %, += 100.00 %) rel step time 0.00 : DoParseResult$12\1 (7106) -> 8 (0.00 %, += 100.00 %) rel step time 0.00 HexDigit:1 (8317) -> 8 (0.00 %, += 100.00 %) rel step time 0.00 HexDigit (8317) -> 8 (0.00 %, += 100.00 %) rel step time 0.00 StringLiteral-Escape-XCode (3398) -> 8 (0.00 %, += 100.00 %) rel step time 0.00 Pipe (3398) -> 8 (0.00 %, += 100.00 %) rel step time 0.00 DoParseResult$12\1 (7106) -> 8 (0.00 %, += 100.00 %) rel step time 0.00 CharFromNum (0000) -> 8 (0.00 %, += 100.00 %) rel step time 0.00 : Pipe$2\1 (3398) -> 6 (0.00 %, += 100.00 %) rel step time 0.00 Pipe$2\1 (3398) -> 6 (0.00 %, += 100.00 %) rel step time 0.00 ASTItemFromDirective (7106) -> 5 (0.00 %, += 100.00 %) rel step time 0.00 ScanEscape$2?2 (8317) -> 4 (0.00 %, += 100.00 %) rel step time 0.00 ScanEscape$2?1 (8317) -> 4 (0.00 %, += 100.00 %) rel step time 0.00 Number (3398) -> 4 (0.00 %, += 100.00 %) rel step time 0.00 CComment-CheckNested (3398) -> 4 (0.00 %, += 100.00 %) rel step time 0.00 Pipe (7045) -> 4 (0.00 %, += 100.00 %) rel step time 0.00 Fetch (7045) -> 4 (0.00 %, += 100.00 %) rel step time 0.00 : Update:1 (7045) -> 3 (0.00 %, += 100.00 %) rel step time 0.00 : Pipe$2\1 (7045) -> 3 (0.00 %, += 100.00 %) rel step time 0.00 : ParseBlocks$1=2 (7106) -> 3 (0.00 %, += 100.00 %) rel step time 0.00 DoOpenEChain$2?3 (3931) -> 3 (0.00 %, += 100.00 %) rel step time 0.00 DoOpenEChain$2?2 (3931) -> 3 (0.00 %, += 100.00 %) rel step time 0.00 DoOpenEChain$2?1 (3931) -> 3 (0.00 %, += 100.00 %) rel step time 0.00 Numb-Overflow=1 (3398) -> 3 (0.00 %, += 100.00 %) rel step time 0.00 Numb-Overflow (3398) -> 3 (0.00 %, += 100.00 %) rel step time 0.00 SwDoArgList (2369) -> 3 (0.00 %, += 100.00 %) rel step time 0.00 DoArgList (2369) -> 3 (0.00 %, += 100.00 %) rel step time 0.00 Update:1 (7045) -> 3 (0.00 %, += 100.00 %) rel step time 0.00 Update (7045) -> 3 (0.00 %, += 100.00 %) rel step time 0.00 Pipe$2\1 (7045) -> 3 (0.00 %, += 100.00 %) rel step time 0.00 ParseBlocks$1=2 (7106) -> 3 (0.00 %, += 100.00 %) rel step time 0.00 ParseBlocks$1=1 (7106) -> 3 (0.00 %, += 100.00 %) rel step time 0.00 Inc (0000) -> 3 (0.00 %, += 100.00 %) rel step time 0.00 Arg (0000) -> 3 (0.00 %, += 100.00 %) rel step time 0.00 : LoadAST$2=2 (5703) -> 2 (0.00 %, += 100.00 %) rel step time 0.00 : LoadAST$2=1 (5703) -> 2 (0.00 %, += 100.00 %) rel step time 0.00 ResolveOpts-LongParam (4990) -> 2 (0.00 %, += 100.00 %) rel step time 0.00 LoadAST$2=2 (5703) -> 2 (0.00 %, += 100.00 %) rel step time 0.00 LoadAST$2=1 (5703) -> 2 (0.00 %, += 100.00 %) rel step time 0.00 NormalizeTokens (3398) -> 2 (0.00 %, += 100.00 %) rel step time 0.00 NormalizeToken$14=1 (3398) -> 2 (0.00 %, += 100.00 %) rel step time 0.00 NormalizeToken$14:1 (3398) -> 2 (0.00 %, += 100.00 %) rel step time 0.00 MapAccum (3398) -> 2 (0.00 %, += 100.00 %) rel step time 0.00 LoadSource:1 (3398) -> 2 (0.00 %, += 100.00 %) rel step time 0.00 LoadSource (3398) -> 2 (0.00 %, += 100.00 %) rel step time 0.00 LexFolding (3398) -> 2 (0.00 %, += 100.00 %) rel step time 0.00 DelAccumulator (3398) -> 2 (0.00 %, += 100.00 %) rel step time 0.00 CollectNativeLines (3398) -> 2 (0.00 %, += 100.00 %) rel step time 0.00 MapAccum (7045) -> 2 (0.00 %, += 100.00 %) rel step time 0.00 ParseInclude (7106) -> 2 (0.00 %, += 100.00 %) rel step time 0.00 (9999) -> 2 (0.00 %, += 100.00 %) rel step time 0.00 UnEscapeString-SR (0000) -> 2 (0.00 %, += 100.00 %) rel step time 0.00 SR-Scan (0000) -> 2 (0.00 %, += 100.00 %) rel step time 0.00 SR-ParseProgram (0000) -> 2 (0.00 %, += 100.00 %) rel step time 0.00 Config-GetTargetMode (0000) -> 2 (0.00 %, += 100.00 %) rel step time 0.00 : OutlineConstants-OneCommand$1=1 (2191) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 : Log-PutLine:1 (2711) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 : RenameTargets=1 (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 : Main-SwRenamedTargets$2=1 (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 : Main-SwRenamedTargets$2:1 (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 : Main-SwFoundFiles$2=1 (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 : LookupSourceFiles=2 (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 : LookupSourceFiles=1 (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 : Link:1 (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 : CorrectTargetFileName$1?1:1$1=3 (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 : CorrectTargetFileName$1?1:1 (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 : ParseCommandLine\2$18=1 (7045) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 ResolveOpts-Short (4990) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 FoldErrors (4990) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 DoFoldErrors (4990) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 Config-GetTempDir:1 (3597) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 OutlineConstants-OneCommand$1=1 (2191) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 TkSwap (6799) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 Log-PutLine:1 (2711) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 Log-Final=1 (2711) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 RenameTargets=1 (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 RenameTargets (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 Main-SwValidCommandLine (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 Main-SwRenamedTargets$2=1 (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 Main-SwRenamedTargets$2:1 (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 Main-SwRenamedTargets (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 Main-SwFoundFiles$2=1 (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 Main-SwFoundFiles (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 Main (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 LookupSourceFiles=2 (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 LookupSourceFiles=1 (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 LookupSourceFiles (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 Link:1 (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 Link (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 CorrectTargetFileName$1?1:1$1=4 (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 CorrectTargetFileName$1?1:1$1=3 (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 CorrectTargetFileName$1?1:1$1=2 (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 CorrectTargetFileName$1?1:1$1=1:1 (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 CorrectTargetFileName$1?1:1$1=1 (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 CorrectTargetFileName$1?1:1 (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 CorrectTargetFileName$1?1 (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 CorrectTargetFileName$1=2 (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 CorrectTargetFileName (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 CompileFiles (4171) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 ParseCommandLine\4 (7045) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 ParseCommandLine\2$18=1 (7045) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 ParseCommandLine\1 (7045) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 ParseCommandLine (0000) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 Log-PutLine (0000) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 Log-Init (0000) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 Log-Final (0000) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 Go (0000) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 GetOpt (0000) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 Config-SetTargetMode (0000) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 Config-SetTargetFileName (0000) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 Config-SetSearchFolder (0000) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 Config-SetPrelude (0000) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 Config-SetKeepRasls (0000) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 Config-GetTempDir (0000) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 Config-GetTargetFileName (0000) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 Config-GetLogFile (0000) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 Config-GetGrammarCheck (0000) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 Config-Create (0000) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 ArgList (0000) -> 1 (0.00 %, += 100.00 %) rel step time 0.00 1. D:\Mazdaywik\Documents\Refal-5-lambda\bin\rlc-core.exe SCOPES: 1. #7013 - rich-prefix-exe.ref 2. #0871 - Library.ref 3. #2546 - Hash.ref 4. #2369 - LibraryEx.ref 5. #4990 - GetOpt.ref 6. #9313 - Platform.ref 7. #4171 - main.ref 8. #7045 - ParseCmdLine.ref 9. #3597 - Config.ref 10. #2811 - ../common/Version.ref 11. #2711 - Log.ref 12. #5501 - DisplayName.ref 13. #3807 - ../common/Escape.ref 14. #6566 - Log-AST.ref 15. #9378 - Error.ref 16. #8133 - ../common/FindFile.ref 17. #5703 - Engine.ref 18. #8897 - Checker.ref 19. #3532 - GenericMatch.ref 20. #3931 - Desugaring-UnCondition.ref 21. #9549 - Desugaring.ref 22. #8317 - R5-Lexer.ref 23. #6799 - R5-Parser.ref 24. #3398 - SR-Lexer.sref 25. #7106 - SR-Parser.sref 26. #3995 - OptTree.ref 27. #3243 - OptTree-AutoMarkup.ref 28. #0636 - OptTree-Drive.ref 29. #2090 - OptTree-Spec.ref 30. #5955 - HighLevelRASL.ref 31. #3990 - HighLevelRASL-DisjointFunc.ref 32. #7236 - HighLevelRASL-Common.ref 33. #0655 - HighLevelRASL-Condition.ref 34. #8477 - HighLevelRASL-GenResult-Simple.ref 35. #4641 - HighLevelRASL-GenSubst-Save.ref 36. #5417 - HighLevelRASL-ConjointFunc.ref 37. #3125 - GlobalGen.ref 38. #0177 - HighLevelRASL-GenSubst-Simple.ref 39. #7170 - HighLevelRASL-GenResult-Opt.ref 40. #7520 - GST.ref 41. #2191 - LowLevelRASL.ref 42. #8023 - LowLevelRASL-RASL.ref 43. #5064 - LowLevelRASL-Native.ref 44. #4746 - Generator-RASL.ref 45. #8132 - Generator-Native.ref