Profiling results ----------------- Total cpu time observed: 10456ms (out of 10460ms) Number of samples taken: 49 (once every 213ms) ======================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ======================================================================== [1] 10456(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [6] 100.0% ------------------------------------------------------------------------ for-loop [75] 100.0% [2] 644(6.2%) 0(0.0%) syntax-e (unknown source) syntax-e/no-taint [7] 100.0% ------------------------------------------------------------------------ for-loop [75] 100.0% [3] 200(1.9%) 0(0.0%) expand-implicit (unknown source) resolve+shift28 [69] 100.0% ------------------------------------------------------------------------ for-loop [75] 100.0% [4] 200(1.9%) 0(0.0%) expand-identifier (unknown source) resolve+shift28 [69] 100.0% ------------------------------------------------------------------------ matcher [49] 100.0% [5] 184(1.8%) 0(0.0%) ??? ...ath-testversion/src/core/matcher.rkt:130:11 for-loop [8] 100.0% ------------------------------------------------------------------------ ??? [1] 100.0% [6] 10456(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [9] 100.0% ------------------------------------------------------------------------ expand-id-application-form17 [65] 24.4% syntax-e [2] 75.6% [7] 852(8.1%) 0(0.0%) syntax-e/no-taint (unknown source) loop [61] 100.0% ------------------------------------------------------------------------ ??? [5] 100.0% [8] 184(1.8%) 0(0.0%) for-loop ...estversion/src/core/matcher.rkt:132:15 matcher [49] 100.0% ------------------------------------------------------------------------ profile-thunk16 [6] 100.0% [9] 10456(100.0%) 0(0.0%) run ...et-7.5/share/pkgs/profile-lib/main.rkt:39:2 .../more-scheme.rkt:261:28 [10] 100.0% ------------------------------------------------------------------------ for-loop [46] 3.9% run [9] 96.1% [10] 10456(100.0%) 214(2.0%) .../more-scheme.rkt:261:28 (unknown source) prepare-points-intervals [11] 49.6% run-improve47 [12] 37.7% oracle-error [13] 3.9% ??? [67] 2.9% errors [38] 2.9% eval-errors [14] 1.9% ------------------------------------------------------------------------ .../more-scheme.rkt:261:28 [10] 100.0% [11] 5190(49.6%) 0(0.0%) prepare-points-intervals ...n/src/points.rkt:110:0 loop [15] 100.0% ------------------------------------------------------------------------ .../more-scheme.rkt:261:28 [10] 100.0% [12] 4256(40.7%) 0(0.0%) run-improve47 ...estversion/src/mainloop.rkt:346:0 for-loop [16] 88.1% ??? [25] 6.6% simplify! [22] 5.3% ------------------------------------------------------------------------ .../more-scheme.rkt:261:28 [10] 100.0% [13] 410(3.9%) 0(0.0%) oracle-error ...h-testversion/src/points.rkt:188:0 for-loop [17] 100.0% ------------------------------------------------------------------------ .../more-scheme.rkt:261:28 [10] 100.0% [14] 200(1.9%) 0(0.0%) eval-errors ...th-testversion/src/points.rkt:179:0 for-loop [18] 100.0% ------------------------------------------------------------------------ prepare-points-intervals [11] 100.0% [15] 5190(49.6%) 0(0.0%) loop ...e/eggmath-testversion/src/points.rkt:123:4 loop [19] 100.0% ------------------------------------------------------------------------ run-improve47 [12] 100.0% [16] 3750(35.9%) 0(0.0%) for-loop ...ath-testversion/src/mainloop.rkt:365:4 run-iter! [20] 100.0% ------------------------------------------------------------------------ oracle-error [13] 100.0% [17] 410(3.9%) 0(0.0%) for-loop ...gmath-testversion/src/points.rkt:189:2 loop [61] 100.0% ------------------------------------------------------------------------ eval-errors [14] 100.0% [18] 200(1.9%) 0(0.0%) for-loop ...gmath-testversion/src/points.rkt:181:2 ??? [67] 100.0% ------------------------------------------------------------------------ loop [15] 100.0% [19] 5190(49.6%) 0(0.0%) loop ...ie/eggmath-testversion/src/points.rkt:81:2 ??? [67] 96.1% ??? [83] 3.9% ------------------------------------------------------------------------ for-loop [16] 100.0% [20] 3750(35.9%) 0(0.0%) run-iter! ...th-testversion/src/mainloop.rkt:327:0 finalize-iter! [21] 66.1% simplify! [22] 22.5% localize! [23] 6.5% gen-rewrites! [24] 4.9% ------------------------------------------------------------------------ run-iter! [20] 100.0% [21] 2480(23.7%) 0(0.0%) finalize-iter! ...stversion/src/mainloop.rkt:278:0 ??? [25] 100.0% ------------------------------------------------------------------------ run-improve47 [12] 21.1% run-iter! [20] 78.9% [22] 1070(10.2%) 0(0.0%) simplify! ...th-testversion/src/mainloop.rkt:224:0 egraph-run [26] 100.0% ------------------------------------------------------------------------ run-iter! [20] 100.0% [23] 242(2.3%) 0(0.0%) localize! ...th-testversion/src/mainloop.rkt:127:0 localize-error [27] 100.0% ------------------------------------------------------------------------ run-iter! [20] 100.0% [24] 184(1.8%) 0(0.0%) gen-rewrites! ...estversion/src/mainloop.rkt:183:0 for-loop [28] 100.0% ------------------------------------------------------------------------ run-improve47 [12] 10.1% finalize-iter! [21] 89.9% [25] 2760(26.4%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:486:18 for-loop [29] 89.9% make-alt-table [31] 10.1% ------------------------------------------------------------------------ simplify! [22] 100.0% [26] 1070(10.2%) 0(0.0%) egraph-run ...estversion/src/core/eggmath.rkt:51:0 egraph-add-exprs [30] 100.0% ------------------------------------------------------------------------ localize! [23] 100.0% [27] 242(2.3%) 0(0.0%) localize-error ...rsion/src/core/localize.rkt:66:0 hash-ref! [32] 100.0% ------------------------------------------------------------------------ gen-rewrites! [24] 100.0% [28] 184(1.8%) 0(0.0%) for-loop ...th-testversion/src/mainloop.rkt:191:11 rewrite-expression-head20 [33] 100.0% ------------------------------------------------------------------------ ??? [25] 100.0% [29] 2480(23.7%) 200(1.9%) for-loop ...estversion/src/core/alt-table.rkt:55:2 atab-add-altn [34] 57.7% errors [38] 34.2% ------------------------------------------------------------------------ egraph-run [26] 100.0% [30] 1070(10.2%) 0(0.0%) egraph-add-exprs ...ion/src/core/eggmath.rkt:111:0 ??? [35] 100.0% ------------------------------------------------------------------------ ??? [25] 100.0% [31] 280(2.7%) 0(0.0%) make-alt-table ...sion/src/core/alt-table.rkt:32:0 errors [38] 100.0% ------------------------------------------------------------------------ localize-error [27] 16.7% map [41] 83.3% [32] 242(2.3%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 parse-loop30 [36] 100.0% ------------------------------------------------------------------------ for-loop [28] 100.0% [33] 184(1.8%) 0(0.0%) rewrite-expression-head20 ...core/matcher.rkt:87:0 rewriter [37] 100.0% ------------------------------------------------------------------------ for-loop [29] 100.0% [34] 1432(13.7%) 0(0.0%) atab-add-altn ...sion/src/core/alt-table.rkt:203:0 errors [38] 86.0% best-and-tied-at-points [42] 14.0% ------------------------------------------------------------------------ egraph-add-exprs [30] 100.0% [35] 1070(10.2%) 0(0.0%) ??? ...math-testversion/src/core/simplify.rkt:72:6 for-loop [39] 100.0% ------------------------------------------------------------------------ hash-ref! [32] 100.0% [36] 242(2.3%) 0(0.0%) parse-loop30 (unknown source) map [41] 83.3% for-loop [40] 16.7% ------------------------------------------------------------------------ matcher [49] 50.0% rewrite-expression-head20 [33] 50.0% [37] 184(1.8%) 0(0.0%) rewriter ...-testversion/src/core/matcher.rkt:90:2 for-loop [43] 50.0% *rules* [44] 50.0% ------------------------------------------------------------------------ make-alt-table [31] 10.1% .../more-scheme.rkt:261:28 [10] 14.5% for-loop [29] 30.7% atab-add-altn [34] 44.6% [38] 2760(26.4%) 0(0.0%) errors ...eggmath-testversion/src/points.rkt:201:0 eval-prog [45] 70.2% for-loop [46] 29.8% ------------------------------------------------------------------------ ??? [35] 100.0% [39] 1070(10.2%) 1070(10.2%) for-loop ...testversion/src/core/simplify.rkt:91:2 ------------------------------------------------------------------------ parse-loop30 [36] 100.0% [40] 242(2.3%) 0(0.0%) for-loop ...estversion/src/core/localize.rkt:53:21 for-loop [47] 100.0% ------------------------------------------------------------------------ parse-loop30 [36] 100.0% [41] 242(2.3%) 0(0.0%) map ...t-7.5/collects/racket/private/map.rkt:35:13 hash-ref! [32] 100.0% ------------------------------------------------------------------------ atab-add-altn [34] 100.0% [42] 200(1.9%) 0(0.0%) best-and-tied-at-points ...ore/alt-table.rkt:117:0 make-sequence [48] 100.0% ------------------------------------------------------------------------ rewriter [37] 100.0% [43] 184(1.8%) 0(0.0%) for-loop ...-testversion/src/core/matcher.rkt:92:4 matcher [49] 100.0% ------------------------------------------------------------------------ rewriter [37] 100.0% [44] 184(1.8%) 0(0.0%) *rules* ...-testversion/src/syntax/rules.rkt:666:0 for-loop [75] 100.0% ------------------------------------------------------------------------ errors [38] 100.0% [45] 1938(18.5%) 0(0.0%) eval-prog ...ath-testversion/src/programs.rkt:83:0 temp85_0 [50] 89.4% loop [61] 10.6% ------------------------------------------------------------------------ errors [38] 100.0% [46] 822(7.9%) 0(0.0%) for-loop ...gmath-testversion/src/points.rkt:203:2 .../more-scheme.rkt:261:28 [10] 100.0% ------------------------------------------------------------------------ for-loop [40] 100.0% [47] 242(2.3%) 242(2.3%) for-loop ...estversion/src/core/localize.rkt:54:23 ------------------------------------------------------------------------ best-and-tied-at-points [42] 100.0% [48] 200(1.9%) 200(1.9%) make-sequence ...ects/racket/private/for.rkt:533:2 ------------------------------------------------------------------------ for-loop [8] 50.0% for-loop [43] 50.0% [49] 184(1.8%) 0(0.0%) matcher ...-testversion/src/core/matcher.rkt:118:2 ??? [5] 50.0% rewriter [37] 50.0% ------------------------------------------------------------------------ eval-prog [45] 100.0% [50] 1732(16.6%) 0(0.0%) temp85_0 (unknown source) compile15 [51] 100.0% ------------------------------------------------------------------------ temp85_0 [50] 100.0% [51] 1732(16.6%) 0(0.0%) compile15 (unknown source) temp91_0 [52] 100.0% ------------------------------------------------------------------------ compile15 [51] 100.0% [52] 1732(16.6%) 0(0.0%) temp91_0 (unknown source) temp118_0 [53] 83.8% compile-top9 [54] 16.2% ------------------------------------------------------------------------ temp91_0 [52] 100.0% [53] 1452(13.9%) 0(0.0%) temp118_0 (unknown source) expand-capturing-lifts [55] 100.0% ------------------------------------------------------------------------ temp91_0 [52] 100.0% [54] 280(2.7%) 0(0.0%) compile-top9 (unknown source) compile-forms33 [56] 100.0% ------------------------------------------------------------------------ loop [61] 12.4% temp118_0 [53] 87.6% [55] 1658(15.9%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [57] 87.6% dispatch-transformer41 [58] 12.4% ------------------------------------------------------------------------ compile-top9 [54] 100.0% [56] 280(2.7%) 0(0.0%) compile-forms33 (unknown source) for-loop [75] 100.0% ------------------------------------------------------------------------ expand-capturing-lifts [55] 100.0% [57] 1452(13.9%) 0(0.0%) lambda-clause-expander (unknown source) finish-bodys [59] 100.0% ------------------------------------------------------------------------ for-loop [75] 49.3% expand-capturing-lifts [55] 50.7% [58] 406(3.9%) 0(0.0%) dispatch-transformer41 (unknown source) apply-transformer52 [60] 100.0% ------------------------------------------------------------------------ lambda-clause-expander [57] 18.1% for-loop [75] 81.9% [59] 1452(13.9%) 0(0.0%) finish-bodys (unknown source) for-loop [75] 100.0% ------------------------------------------------------------------------ dispatch-transformer41 [58] 100.0% [60] 406(3.9%) 0(0.0%) apply-transformer52 (unknown source) apply-post-expansion [62] 50.7% loop [61] 49.3% ------------------------------------------------------------------------ eval-prog [45] 1.0% gf [63] 4.1% for-loop [75] 6.2% loop [61] 7.2% apply-transformer52 [60] 12.0% for-loop [17] 24.6% syntax-e/no-taint [7] 44.8% [61] 1668(16.0%) 0(0.0%) loop (unknown source) gf [63] 49.0% ??? [64] 12.5% ??? [67] 12.1% core-form-sym [66] 12.0% loop [61] 7.2% expand-id-application-form17 [65] 6.2% expand-capturing-lifts [55] 1.0% ------------------------------------------------------------------------ apply-transformer52 [60] 100.0% [62] 206(2.0%) 0(0.0%) apply-post-expansion (unknown source) gf [63] 100.0% ------------------------------------------------------------------------ apply-post-expansion [62] 3.9% loop [61] 96.1% [63] 1058(10.1%) 206(2.0%) gf (unknown source) propagation-apply [68] 80.5% loop [61] 15.6% ------------------------------------------------------------------------ loop [61] 100.0% [64] 208(2.0%) 0(0.0%) ??? ...e/eggmath-testversion/src/points.rkt:190:26 ??? [67] 100.0% ------------------------------------------------------------------------ loop [61] 100.0% [65] 208(2.0%) 0(0.0%) expand-id-application-form17 (unknown source) syntax-e/no-taint [7] 100.0% ------------------------------------------------------------------------ loop [61] 100.0% [66] 200(1.9%) 0(0.0%) core-form-sym (unknown source) resolve+shift28 [69] 100.0% ------------------------------------------------------------------------ for-loop [18] 3.2% loop [61] 3.3% ??? [64] 3.4% .../more-scheme.rkt:261:28 [10] 9.8% loop [19] 80.4% [67] 6208(59.4%) 410(3.9%) ??? .../eggmath-testversion/src/programs.rkt:115:2 ??? [70] 57.2% ??? [71] 20.0% ??? [72] 16.3% ------------------------------------------------------------------------ gf [63] 100.0% [68] 852(8.1%) 852(8.1%) propagation-apply (unknown source) ------------------------------------------------------------------------ expand-identifier [4] 33.3% expand-implicit [3] 33.3% core-form-sym [66] 33.3% [69] 600(5.7%) 0(0.0%) resolve+shift28 (unknown source) fallback-loop [73] 100.0% ------------------------------------------------------------------------ ??? [67] 100.0% [70] 3548(33.9%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:375:33 ival-mult [74] 70.3% ival-pow [76] 29.7% ------------------------------------------------------------------------ ??? [67] 100.0% [71] 1240(11.9%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:379:33 ival-add [77] 83.9% ival-div [78] 16.1% ------------------------------------------------------------------------ ??? [67] 100.0% [72] 1010(9.7%) 810(7.7%) ??? ...th-testversion/src/syntax/syntax.rkt:214:17 return/no-unsupplied [79] 19.8% ------------------------------------------------------------------------ resolve+shift28 [69] 100.0% [73] 600(5.7%) 200(1.9%) fallback-loop (unknown source) for-loop [75] 66.7% ------------------------------------------------------------------------ ??? [70] 100.0% [74] 2496(23.9%) 1252(12.0%) ival-mult ...testversion/src/biginterval.rkt:119:0 bfmul [80] 49.8% ------------------------------------------------------------------------ fallback-loop [73] 0.7% for-loop [75] 2.5% *rules* [44] 9.6% compile-forms33 [56] 14.6% finish-bodys [59] 72.6% [75] 1916(18.3%) 400(3.8%) for-loop (unknown source) finish-bodys [59] 59.0% compile-module-linklet57 [81] 14.6% loop [61] 10.9% ormap [82] 9.6% for-loop [75] 2.5% syntax-e [2] 1.4% expand-implicit [3] 0.6% expand-identifier [4] 0.4% dispatch-transformer41 [58] 0.3% ------------------------------------------------------------------------ ??? [70] 100.0% [76] 1052(10.1%) 850(8.1%) ival-pow ...-testversion/src/biginterval.rkt:229:0 bfmul [80] 19.2% ------------------------------------------------------------------------ ??? [71] 100.0% [77] 1040(9.9%) 222(2.1%) ival-add ...-testversion/src/biginterval.rkt:101:0 bfmul [80] 78.7% ------------------------------------------------------------------------ ??? [71] 100.0% [78] 200(1.9%) 200(1.9%) ival-div ...-testversion/src/biginterval.rkt:155:0 ------------------------------------------------------------------------ ??? [72] 100.0% [79] 200(1.9%) 200(1.9%) return/no-unsupplied ...ct/private/arr-i.rkt:566:0 ------------------------------------------------------------------------ ival-pow [76] 8.9% ival-add [77] 36.1% ival-mult [74] 54.9% [80] 2264(21.7%) 0(0.0%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:835:4 ??? [83] 62.9% new-mpfr [84] 37.1% ------------------------------------------------------------------------ for-loop [75] 100.0% [81] 280(2.7%) 280(2.7%) compile-module-linklet57 (unknown source) ------------------------------------------------------------------------ for-loop [75] 100.0% [82] 184(1.8%) 184(1.8%) ormap ....5/collects/racket/private/map.rkt:141:13 ------------------------------------------------------------------------ loop [19] 12.3% bfmul [80] 87.7% [83] 1624(15.5%) 1624(15.5%) ??? /opt/racket-7.5/collects/ffi/unsafe.rkt:938:4 ------------------------------------------------------------------------ bfmul [80] 100.0% [84] 840(8.0%) 840(8.0%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:348:0 ------------------------------------------------------------------------