Profiling results ----------------- Total cpu time observed: 8958ms (out of 9280ms) Number of samples taken: 32 (once every 280ms) ==================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ==================================================================== ??? [60] 100.0% [1] 8958(100.0%) 0(0.0%) run-improve43 ...us-cleanup/src/mainloop.rkt:339:0 for-loop [8] 100.0% -------------------------------------------------------------------- ??? [60] 100.0% [2] 8958(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [7] 100.0% -------------------------------------------------------------------- ??? [60] 100.0% [3] 612(6.8%) 0(0.0%) eval-const-expr ...-cleanup/src/programs.rkt:102:0 eval-prog [9] 100.0% -------------------------------------------------------------------- taylor [58] 100.0% [4] 300(3.3%) 0(0.0%) map ...t-7.0/collects/racket/private/map.rkt:35:13 taylor-invert [10] 100.0% -------------------------------------------------------------------- matcher [50] 100.0% [5] 288(3.2%) 0(0.0%) ??? ...various-cleanup/src/core/matcher.rkt:130:11 for-loop [11] 100.0% -------------------------------------------------------------------- for-loop [55] 100.0% [6] 286(3.2%) 0(0.0%) expand-implicit (unknown source) resolve+shift28 [12] 100.0% -------------------------------------------------------------------- profile-thunk16 [2] 100.0% [7] 8958(100.0%) 0(0.0%) run ...et-7.0/share/pkgs/profile-lib/main.rkt:39:2 ??? [60] 100.0% -------------------------------------------------------------------- run-improve43 [1] 100.0% [8] 8958(100.0%) 0(0.0%) for-loop ...various-cleanup/src/mainloop.rkt:354:4 run-iter! [13] 100.0% -------------------------------------------------------------------- eval-const-expr [3] 100.0% [9] 612(6.8%) 0(0.0%) eval-prog ...various-cleanup/src/programs.rkt:83:0 temp68_2 [14] 100.0% -------------------------------------------------------------------- map [4] 100.0% [10] 300(3.3%) 0(0.0%) taylor-invert ...cleanup/src/core/taylor.rkt:318:0 first-nonzero-exp [15] 100.0% -------------------------------------------------------------------- ??? [5] 100.0% [11] 288(3.2%) 0(0.0%) for-loop ...us-cleanup/src/core/matcher.rkt:132:15 matcher [50] 100.0% -------------------------------------------------------------------- expand-implicit [6] 100.0% [12] 286(3.2%) 0(0.0%) resolve+shift28 (unknown source) fallback-loop [16] 100.0% -------------------------------------------------------------------- for-loop [8] 100.0% [13] 8958(100.0%) 0(0.0%) run-iter! ...arious-cleanup/src/mainloop.rkt:320:0 simplify! [17] 93.4% gen-series! [19] 3.3% gen-rewrites! [20] 3.2% -------------------------------------------------------------------- eval-prog [9] 100.0% [14] 612(6.8%) 0(0.0%) temp68_2 (unknown source) compile16 [18] 100.0% -------------------------------------------------------------------- taylor-invert [10] 100.0% [15] 300(3.3%) 0(0.0%) first-nonzero-exp ...nup/src/core/taylor.rkt:266:0 hash-ref! [66] 100.0% -------------------------------------------------------------------- resolve+shift28 [12] 100.0% [16] 286(3.2%) 0(0.0%) fallback-loop (unknown source) for-loop [55] 100.0% -------------------------------------------------------------------- run-iter! [13] 100.0% [17] 8370(93.4%) 0(0.0%) simplify! ...arious-cleanup/src/mainloop.rkt:217:0 ??? [21] 100.0% -------------------------------------------------------------------- temp68_2 [14] 100.0% [18] 612(6.8%) 0(0.0%) compile16 (unknown source) temp74_0 [22] 100.0% -------------------------------------------------------------------- run-iter! [13] 100.0% [19] 300(3.3%) 0(0.0%) gen-series! ...ious-cleanup/src/mainloop.rkt:153:0 for-loop [23] 100.0% -------------------------------------------------------------------- run-iter! [13] 100.0% [20] 288(3.2%) 0(0.0%) gen-rewrites! ...us-cleanup/src/mainloop.rkt:176:0 for-loop [24] 100.0% -------------------------------------------------------------------- simplify! [17] 100.0% [21] 8370(93.4%) 0(0.0%) ??? .../various-cleanup/src/core/simplify.rkt:26:0 for-loop [25] 69.9% loop [30] 30.1% -------------------------------------------------------------------- compile16 [18] 100.0% [22] 612(6.8%) 0(0.0%) temp74_0 (unknown source) expand-single [26] 100.0% -------------------------------------------------------------------- gen-series! [19] 100.0% [23] 300(3.3%) 0(0.0%) for-loop ...various-cleanup/src/mainloop.rkt:160:7 taylor-alt [27] 100.0% -------------------------------------------------------------------- gen-rewrites! [20] 100.0% [24] 288(3.2%) 0(0.0%) for-loop ...arious-cleanup/src/mainloop.rkt:184:11 rewrite-expression-head20 [28]100.0% -------------------------------------------------------------------- ??? [21] 100.0% [25] 5850(65.3%) 0(0.0%) for-loop ...ous-cleanup/src/core/simplify.rkt:35:2 one-iter [29] 84.3% loop [30] 15.7% -------------------------------------------------------------------- temp74_0 [22] 100.0% [26] 612(6.8%) 0(0.0%) expand-single (unknown source) expand-capturing-lifts [31] 100.0% -------------------------------------------------------------------- for-loop [23] 100.0% [27] 300(3.3%) 0(0.0%) taylor-alt ...rious-cleanup/src/mainloop.rkt:139:0 for-loop [32] 100.0% -------------------------------------------------------------------- for-loop [24] 100.0% [28] 288(3.2%) 0(0.0%) rewrite-expression-head20 ...core/matcher.rkt:87:0 rewriter [33] 100.0% -------------------------------------------------------------------- for-loop [25] 100.0% [29] 4930(55.0%) 0(0.0%) one-iter ...ous-cleanup/src/core/simplify.rkt:92:0 for-loop [35] 55.0% find-matches [36] 32.6% for-loop [37] 12.4% -------------------------------------------------------------------- for-loop [25] 26.7% ??? [21] 73.3% [30] 3440(38.4%) 0(0.0%) loop ...arious-cleanup/src/core/simplify.rkt:158:2 for-loop [34] 100.0% -------------------------------------------------------------------- expand-single [26] 100.0% [31] 612(6.8%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [38] 100.0% -------------------------------------------------------------------- taylor-alt [27] 100.0% [32] 300(3.3%) 0(0.0%) for-loop ...various-cleanup/src/mainloop.rkt:145:6 ??? [39] 100.0% -------------------------------------------------------------------- matcher [50] 50.0% rewrite-expression-head20 [28] 50.0% [33] 288(3.2%) 0(0.0%) rewriter ...ious-cleanup/src/core/matcher.rkt:90:2 for-loop [40] 100.0% -------------------------------------------------------------------- loop [30] 100.0% [34] 3440(38.4%) 0(0.0%) for-loop ...us-cleanup/src/core/simplify.rkt:162:4 loop [42] 52.9% argmin [44] 24.4% expression-cost [53] 14.9% for-loop [51] 7.8% -------------------------------------------------------------------- one-iter [29] 100.0% [35] 2712(30.3%) 0(0.0%) for-loop ...us-cleanup/src/core/simplify.rkt:101:2 loop [41] 88.0% reduce-to-single! [47] 12.0% -------------------------------------------------------------------- one-iter [29] 100.0% [36] 1606(17.9%) 0(0.0%) find-matches ...cleanup/src/core/simplify.rkt:62:0 for-loop [43] 100.0% -------------------------------------------------------------------- one-iter [29] 100.0% [37] 612(6.8%) 0(0.0%) for-loop ...us-cleanup/src/core/simplify.rkt:117:2 set-precompute! [45] 100.0% -------------------------------------------------------------------- expand-capturing-lifts [31] 100.0% [38] 612(6.8%) 0(0.0%) lambda-clause-expander (unknown source) finish-bodys [46] 100.0% -------------------------------------------------------------------- location-do [48] 50.0% for-loop [32] 50.0% [39] 300(3.3%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:357:33 location-do [48] 50.0% approximate9 [49] 50.0% -------------------------------------------------------------------- rewriter [33] 100.0% [40] 288(3.2%) 0(0.0%) for-loop ...ious-cleanup/src/core/matcher.rkt:92:4 matcher [50] 100.0% -------------------------------------------------------------------- for-loop [35] 100.0% [41] 2386(26.6%) 0(0.0%) loop ...arious-cleanup/src/core/simplify.rkt:106:4 apply-match [52] 100.0% -------------------------------------------------------------------- for-loop [34] 100.0% [42] 1820(20.3%) 0(0.0%) loop .../racket-7.0/collects/racket/list.rkt:751:4 expression-cost [53] 58.7% for-loop [54] 41.3% -------------------------------------------------------------------- for-loop [43] 50.0% find-matches [36] 50.0% [43] 1606(17.9%) 0(0.0%) for-loop ...ous-cleanup/src/core/simplify.rkt:64:8 for-loop [43] 50.0% match-e [62] 50.0% -------------------------------------------------------------------- for-loop [34] 100.0% [44] 840(9.4%) 0(0.0%) argmin ...acket-7.0/collects/racket/list.rkt:765:0 for-loop [54] 70.5% expression-cost [53] 29.5% -------------------------------------------------------------------- for-loop [37] 100.0% [45] 612(6.8%) 0(0.0%) set-precompute! ...nup/src/core/simplify.rkt:123:0 for-loop [56] 100.0% -------------------------------------------------------------------- for-loop [55] 23.4% lambda-clause-expander [38] 76.6% [46] 612(6.8%) 0(0.0%) finish-bodys (unknown source) for-loop [55] 100.0% -------------------------------------------------------------------- for-loop [35] 100.0% [47] 326(3.6%) 0(0.0%) reduce-to-single! ...nup/src/core/egraph.rkt:318:0 for-loop [57] 100.0% -------------------------------------------------------------------- ??? [39] 100.0% [48] 300(3.3%) 0(0.0%) location-do ...rious-cleanup/src/programs.rkt:64:0 ??? [39] 100.0% -------------------------------------------------------------------- ??? [39] 100.0% [49] 300(3.3%) 0(0.0%) approximate9 ...s-cleanup/src/core/taylor.rkt:12:0 taylor [58] 100.0% -------------------------------------------------------------------- for-loop [11] 33.3% for-loop [40] 66.7% [50] 288(3.2%) 0(0.0%) matcher ...ious-cleanup/src/core/matcher.rkt:118:2 ??? [5] 33.3% rewriter [33] 33.3% constant? [65] 33.3% -------------------------------------------------------------------- for-loop [34] 100.0% [51] 268(3.0%) 0(0.0%) for-loop ...s-cleanup/src/core/simplify.rkt:166:16 for-loop [59] 100.0% -------------------------------------------------------------------- loop [41] 100.0% [52] 2386(26.6%) 0(0.0%) apply-match ...-cleanup/src/core/simplify.rkt:71:0 for-loop [61] 89.6% match-e [62] 10.4% -------------------------------------------------------------------- argmin [44] 13.6% for-loop [34] 28.0% loop [42] 58.4% [53] 1828(20.4%) 0(0.0%) expression-cost ...-cleanup/src/programs.rkt:157:0 compile [63] 100.0% -------------------------------------------------------------------- argmin [44] 44.0% loop [42] 56.0% [54] 1344(15.0%) 0(0.0%) for-loop ...various-cleanup/src/programs.rkt:158:2 table-ref [64] 100.0% -------------------------------------------------------------------- fallback-loop [16] 9.3% finish-bodys [46] 36.4% for-loop [55] 54.2% [55] 612(6.8%) 612(6.8%) for-loop (unknown source) for-loop [55] 54.2% finish-bodys [46] 9.3% expand-implicit [6] 9.3% -------------------------------------------------------------------- set-precompute! [45] 100.0% [56] 612(6.8%) 0(0.0%) for-loop ...us-cleanup/src/core/simplify.rkt:125:2 ??? [60] 100.0% -------------------------------------------------------------------- reduce-to-single! [47] 100.0% [57] 326(3.6%) 0(0.0%) for-loop ...ious-cleanup/src/core/egraph.rkt:319:8 constant? [65] 100.0% -------------------------------------------------------------------- taylor [58] 50.0% approximate9 [49] 50.0% [58] 300(3.3%) 0(0.0%) taylor ...arious-cleanup/src/core/taylor.rkt:170:0 taylor [58] 50.0% map [4] 50.0% -------------------------------------------------------------------- for-loop [51] 100.0% [59] 268(3.0%) 268(3.0%) for-loop ...s-cleanup/src/core/simplify.rkt:170:23 -------------------------------------------------------------------- for-loop [56] 2.3% run [7] 48.9% [60] 8958(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [2] 48.9% run-improve43 [1] 48.9% eval-const-expr [3] 2.3% -------------------------------------------------------------------- apply-match [52] 100.0% [61] 2138(23.9%) 0(0.0%) for-loop ...ous-cleanup/src/core/simplify.rkt:84:2 merge-egraph-nodes! [68] 74.9% substitute-e [69] 25.1% -------------------------------------------------------------------- apply-match [52] 6.7% for-loop [72] 45.7% for-loop [43] 47.6% [62] 1854(20.7%) 318(3.5%) match-e ...arious-cleanup/src/core/ematch.rkt:39:0 for-loop [67] 81.1% variable? [76] 7.6% ??? [74] 5.6% -------------------------------------------------------------------- expression-cost [53] 100.0% [63] 1828(20.4%) 0(0.0%) compile .../various-cleanup/src/programs.rkt:134:0 hash-ref! [66] 100.0% -------------------------------------------------------------------- for-loop [54] 100.0% [64] 1344(15.0%) 1098(12.3%) table-ref ...e/various-cleanup/src/common.rkt:41:0 assoc-iterate-first [70] 18.3% -------------------------------------------------------------------- matcher [50] 46.9% for-loop [57] 53.1% [65] 614(6.9%) 0(0.0%) constant? ...s-cleanup/src/syntax/syntax.rkt:721:0 value? [81] 100.0% -------------------------------------------------------------------- first-nonzero-exp [15] 14.1% ??? [71] 25.1% compile [63] 60.8% [66] 2128(23.8%) 1314(14.7%) hash-ref! .../racket/private/more-scheme.rkt:376:2 ??? [71] 49.2% simplify [77] 14.1% -------------------------------------------------------------------- match-e [62] 100.0% [67] 1854(20.7%) 696(7.8%) for-loop ...ious-cleanup/src/core/ematch.rkt:52:11 for-loop [72] 71.3% -------------------------------------------------------------------- merge-egraph-nodes! [68] 7.7% for-loop [78] 8.2% for-loop [61] 84.1% [68] 1602(17.9%) 0(0.0%) merge-egraph-nodes! ...p/src/core/egraph.rkt:143:0 update-leader! [73] 68.8% loop! [79] 15.4% for-loop [78] 8.2% merge-egraph-nodes! [68] 7.7% -------------------------------------------------------------------- for-loop [75] 50.0% for-loop [61] 50.0% [69] 536(6.0%) 0(0.0%) substitute-e ...s-cleanup/src/core/ematch.rkt:64:0 for-loop [75] 50.0% ??? [74] 26.5% variable? [76] 23.5% -------------------------------------------------------------------- table-ref [64] 100.0% [70] 246(2.7%) 246(2.7%) assoc-iterate-first ...cket/private/dict.rkt:166:0 -------------------------------------------------------------------- hash-ref! [66] 100.0% [71] 1582(17.7%) 514(5.7%) ??? ...rbie/various-cleanup/src/programs.rkt:142:5 hash-ref! [66] 67.5% -------------------------------------------------------------------- for-loop [67] 100.0% [72] 1486(16.6%) 248(2.8%) for-loop ...ious-cleanup/src/core/ematch.rkt:58:37 match-e [62] 91.7% -------------------------------------------------------------------- merge-egraph-nodes! [68] 100.0% [73] 1356(15.1%) 0(0.0%) update-leader! ...leanup/src/core/egraph.rkt:211:0 for-loop [80] 100.0% -------------------------------------------------------------------- substitute-e [69] 47.8% match-e [62] 52.2% [74] 594(6.6%) 594(6.6%) ??? ...contract/private/arrow-val-first.rkt:388:18 -------------------------------------------------------------------- substitute-e [69] 100.0% [75] 536(6.0%) 0(0.0%) for-loop ...ious-cleanup/src/core/ematch.rkt:73:24 substitute-e [69] 100.0% -------------------------------------------------------------------- substitute-e [69] 47.2% match-e [62] 52.8% [76] 534(6.0%) 0(0.0%) variable? ...s-cleanup/src/syntax/syntax.rkt:724:0 value? [81] 100.0% -------------------------------------------------------------------- hash-ref! [66] 100.0% [77] 300(3.3%) 0(0.0%) simplify ...rious-cleanup/src/core/reduce.rkt:16:0 debug-print [82] 100.0% -------------------------------------------------------------------- merge-egraph-nodes! [68] 100.0% [78] 262(2.9%) 0(0.0%) for-loop ...ious-cleanup/src/core/egraph.rkt:197:4 merge-egraph-nodes! [68] 100.0% -------------------------------------------------------------------- merge-egraph-nodes! [68] 100.0% [79] 246(2.7%) 0(0.0%) loop! .../various-cleanup/src/core/enode.rkt:190:2 custom-set-union [83] 100.0% -------------------------------------------------------------------- update-leader! [73] 100.0% [80] 1356(15.1%) 262(2.9%) for-loop ...ious-cleanup/src/core/egraph.rkt:216:6 for-loop [84] 80.7% -------------------------------------------------------------------- variable? [76] 46.5% constant? [65] 53.5% [81] 1148(12.8%) 1148(12.8%) value? ...arious-cleanup/src/syntax/types.rkt:18:0 -------------------------------------------------------------------- simplify [77] 100.0% [82] 300(3.3%) 0(0.0%) debug-print ...various-cleanup/src/debug.rkt:110:0 ??? [85] 100.0% -------------------------------------------------------------------- loop! [79] 100.0% [83] 246(2.7%) 246(2.7%) custom-set-union ...et/private/set-types.rkt:168:0 -------------------------------------------------------------------- for-loop [80] 100.0% [84] 1094(12.2%) 0(0.0%) for-loop ...ious-cleanup/src/core/egraph.rkt:217:8 hash-update! [86] 100.0% -------------------------------------------------------------------- debug-print [82] 100.0% [85] 300(3.3%) 0(0.0%) ??? .../contract/private/arrow-val-first.rkt:428:3 ~r78 [87] 100.0% -------------------------------------------------------------------- for-loop [84] 100.0% [86] 1094(12.2%) 0(0.0%) hash-update! ...cket/private/more-scheme.rkt:365:4 ??? [88] 100.0% -------------------------------------------------------------------- ??? [85] 100.0% [87] 300(3.3%) 300(3.3%) ~r78 ...acket-7.0/collects/racket/format.rkt:189:0 -------------------------------------------------------------------- hash-update! [86] 100.0% [88] 1094(12.2%) 0(0.0%) ??? .../various-cleanup/src/core/egraph.rkt:219:24 for-loop [89] 100.0% -------------------------------------------------------------------- ??? [88] 100.0% [89] 1094(12.2%) 1094(12.2%) for-loop ...ous-cleanup/src/core/egraph.rkt:220:26 --------------------------------------------------------------------