Profiling results ----------------- Total cpu time observed: 20356ms (out of 20552ms) Number of samples taken: 99 (once every 206ms) (Hiding functions with self<1.0% and local<2.0%: 1 of 106 hidden) ========================================================================= Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ========================================================================= [1] 20356(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [4] 100.0% ------------------------------------------------------------------------- ??? [84] 100.0% [2] 600(2.9%) 0(0.0%) approximate9 ...eval-prog/src/core/taylor.rkt:11:0 loop [5] 100.0% ------------------------------------------------------------------------- hash-ref! [73] 100.0% [3] 384(1.9%) 0(0.0%) taylor ...ster-eval-prog/src/core/taylor.rkt:169:0 loop [6] 100.0% ------------------------------------------------------------------------- ??? [1] 100.0% [4] 20356(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [7] 100.0% ------------------------------------------------------------------------- approximate9 [2] 100.0% [5] 600(2.9%) 0(0.0%) loop .../faster-eval-prog/src/core/taylor.rkt:86:4 hash-ref! [73] 100.0% ------------------------------------------------------------------------- taylor [3] 100.0% [6] 384(1.9%) 0(0.0%) loop ...faster-eval-prog/src/core/taylor.rkt:267:2 hash-ref! [73] 100.0% ------------------------------------------------------------------------- profile-thunk16 [4] 100.0% [7] 20356(100.0%) 0(0.0%) run ...et-7.5/share/pkgs/profile-lib/main.rkt:39:2 .../more-scheme.rkt:261:28 [8] 100.0% ------------------------------------------------------------------------- for-loop [70] 19.8% run [7] 80.2% [8] 20356(100.0%) 200(1.0%) .../more-scheme.rkt:261:28 (unknown source) run-improve47 [9] 28.3% oracle-error [10] 27.7% ??? [72] 19.3% prepare-points-intervals [11] 10.9% errors [64] 5.0% get-final-combination [12] 4.5% eval-errors [66] 2.9% baseline-error [13] 1.0% ------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [8] 100.0% [9] 8678(42.6%) 0(0.0%) run-improve47 ...-eval-prog/src/mainloop.rkt:346:0 for-loop [14] 100.0% ------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [8] 100.0% [10] 5636(27.7%) 0(0.0%) oracle-error ...ter-eval-prog/src/points.rkt:177:0 for-loop [15] 100.0% ------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [8] 100.0% [11] 2210(10.9%) 0(0.0%) prepare-points-intervals ...g/src/points.rkt:120:0 loop [16] 100.0% ------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [8] 100.0% [12] 1208(5.9%) 0(0.0%) get-final-combination ...og/src/mainloop.rkt:371:0 infer-splitpoints [17] 100.0% ------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [8] 100.0% [13] 200(1.0%) 0(0.0%) baseline-error ...r-eval-prog/src/points.rkt:181:0 argmin [55] 100.0% ------------------------------------------------------------------------- run-improve47 [9] 100.0% [14] 8678(42.6%) 0(0.0%) for-loop ...aster-eval-prog/src/mainloop.rkt:365:4 run-iter! [18] 100.0% ------------------------------------------------------------------------- oracle-error [10] 100.0% [15] 5636(27.7%) 0(0.0%) for-loop .../faster-eval-prog/src/points.rkt:178:2 loop [19] 71.5% map [82] 28.5% ------------------------------------------------------------------------- prepare-points-intervals [11] 100.0% [16] 2210(10.9%) 0(0.0%) loop ...rbie/faster-eval-prog/src/points.rkt:132:4 loop [20] 91.0% ??? [22] 9.0% ------------------------------------------------------------------------- get-final-combination [12] 100.0% [17] 1208(5.9%) 0(0.0%) infer-splitpoints ...rog/src/core/regimes.rkt:33:0 for-loop [21] 100.0% ------------------------------------------------------------------------- for-loop [14] 100.0% [18] 8678(42.6%) 0(0.0%) run-iter! ...ster-eval-prog/src/mainloop.rkt:327:0 finalize-iter! [23] 71.9% simplify! [26] 11.8% gen-series! [27] 6.9% localize! [28] 4.7% gen-rewrites! [29] 2.5% choose-best-alt! [31] 2.3% ------------------------------------------------------------------------- loop [19] 17.5% for-loop [15] 82.5% [19] 4032(19.8%) 0(0.0%) loop (unknown source) ??? [24] 70.1% loop [19] 17.5% ??? [72] 12.4% ------------------------------------------------------------------------- loop [16] 100.0% [20] 2010(9.9%) 200(1.0%) loop ...erbie/faster-eval-prog/src/points.rkt:79:2 ??? [72] 90.0% ------------------------------------------------------------------------- infer-splitpoints [17] 100.0% [21] 1208(5.9%) 0(0.0%) for-loop ...er-eval-prog/src/core/regimes.rkt:45:6 option-on-expr [25] 100.0% ------------------------------------------------------------------------- loop [16] 100.0% [22] 200(1.0%) 0(0.0%) ??? ...erbie/faster-eval-prog/src/points.rkt:113:6 sample-multi-bounded [30] 100.0% ------------------------------------------------------------------------- run-iter! [18] 100.0% [23] 6236(30.6%) 0(0.0%) finalize-iter! ...eval-prog/src/mainloop.rkt:278:0 ??? [32] 100.0% ------------------------------------------------------------------------- loop [94] 11.9% map [82] 19.9% loop [19] 68.1% [24] 5034(24.7%) 0(0.0%) ??? ...rbie/faster-eval-prog/src/points.rkt:179:26 ??? [72] 92.0% ulp-difference [37] 8.0% ------------------------------------------------------------------------- for-loop [21] 100.0% [25] 1208(5.9%) 0(0.0%) option-on-expr ...-prog/src/core/regimes.rkt:124:0 for-loop [34] 49.7% sort-context-on-expr [38] 33.3% err-lsts->split-indices [40] 17.1% ------------------------------------------------------------------------- run-iter! [18] 100.0% [26] 1020(5.0%) 0(0.0%) simplify! ...ster-eval-prog/src/mainloop.rkt:224:0 ??? [33] 100.0% ------------------------------------------------------------------------- run-iter! [18] 100.0% [27] 600(2.9%) 0(0.0%) gen-series! ...er-eval-prog/src/mainloop.rkt:160:0 for-loop [35] 100.0% ------------------------------------------------------------------------- run-iter! [18] 100.0% [28] 406(2.0%) 0(0.0%) localize! ...ster-eval-prog/src/mainloop.rkt:127:0 localize-error [36] 100.0% ------------------------------------------------------------------------- run-iter! [18] 100.0% [29] 216(1.1%) 0(0.0%) gen-rewrites! ...-eval-prog/src/mainloop.rkt:183:0 for-loop [39] 100.0% ------------------------------------------------------------------------- ??? [22] 100.0% [30] 200(1.0%) 0(0.0%) sample-multi-bounded ...l-prog/src/points.rkt:14:0 random-ranges [41] 100.0% ------------------------------------------------------------------------- run-iter! [18] 100.0% [31] 200(1.0%) 0(0.0%) choose-best-alt! ...al-prog/src/mainloop.rkt:117:0 ...ow-val-first.rkt:486:18 [42] 100.0% ------------------------------------------------------------------------- finalize-iter! [23] 100.0% [32] 6236(30.6%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:486:18 for-loop [43] 96.7% atab-add-altns [49] 3.3% ------------------------------------------------------------------------- simplify! [26] 100.0% [33] 1020(5.0%) 0(0.0%) ??? ...faster-eval-prog/src/core/simplify.rkt:42:0 egraph-run [44] 100.0% ------------------------------------------------------------------------- option-on-expr [25] 100.0% [34] 600(2.9%) 0(0.0%) for-loop ...r-eval-prog/src/core/regimes.rkt:135:4 errors [64] 100.0% ------------------------------------------------------------------------- gen-series! [27] 100.0% [35] 600(2.9%) 0(0.0%) for-loop ...aster-eval-prog/src/mainloop.rkt:167:7 taylor-alt [45] 100.0% ------------------------------------------------------------------------- localize! [28] 100.0% [36] 406(2.0%) 0(0.0%) localize-error ...-prog/src/core/localize.rkt:58:0 hash-ref! [73] 100.0% ------------------------------------------------------------------------- ??? [24] 100.0% [37] 402(2.0%) 402(2.0%) ulp-difference ...ter-eval-prog/src/float.rkt:19:0 ------------------------------------------------------------------------- option-on-expr [25] 100.0% [38] 402(2.0%) 0(0.0%) sort-context-on-expr ...src/core/regimes.rkt:117:0 ??? [46] 100.0% ------------------------------------------------------------------------- gen-rewrites! [29] 100.0% [39] 216(1.1%) 0(0.0%) for-loop ...aster-eval-prog/src/mainloop.rkt:208:4 for-loop [47] 100.0% ------------------------------------------------------------------------- option-on-expr [25] 100.0% [40] 206(1.0%) 0(0.0%) err-lsts->split-indices .../core/regimes.rkt:263:0 loop [48] 100.0% ------------------------------------------------------------------------- sample-multi-bounded [30] 100.0% [41] 200(1.0%) 0(0.0%) random-ranges ...er-eval-prog/src/common.rkt:164:0 build--> [50] 100.0% ------------------------------------------------------------------------- choose-best-alt! [31] 100.0% [42] 200(1.0%) 0(0.0%) ...ow-val-first.rkt:486:18 (unknown source) ??? [51] 100.0% ------------------------------------------------------------------------- ??? [32] 100.0% [43] 6030(29.6%) 0(0.0%) for-loop ...-eval-prog/src/core/alt-table.rkt:46:2 atab-add-altn [52] 63.3% errors [64] 36.7% ------------------------------------------------------------------------- ??? [33] 100.0% [44] 1020(5.0%) 0(0.0%) egraph-run .../pkgs/egg-herbie-linux/main.rkt:49:0 egraph-add-exprs [53] 100.0% ------------------------------------------------------------------------- for-loop [35] 100.0% [45] 600(2.9%) 0(0.0%) taylor-alt ...ter-eval-prog/src/mainloop.rkt:146:0 for-loop [54] 100.0% ------------------------------------------------------------------------- sort-context-on-expr [38] 100.0% [46] 402(2.0%) 0(0.0%) ??? ...-7.5/collects/racket/private/sort.rkt:369:3 loop [67] 50.2% generic-sort/key [57] 49.8% ------------------------------------------------------------------------- for-loop [39] 100.0% [47] 216(1.1%) 0(0.0%) for-loop ...aster-eval-prog/src/mainloop.rkt:209:6 ??? [84] 100.0% ------------------------------------------------------------------------- err-lsts->split-indices [40] 100.0% [48] 206(1.0%) 0(0.0%) loop ...aster-eval-prog/src/core/regimes.rkt:314:4 for-loop [56] 100.0% ------------------------------------------------------------------------- ??? [32] 100.0% [49] 206(1.0%) 0(0.0%) atab-add-altns ...prog/src/core/alt-table.rkt:40:0 atab-add-altn [52] 100.0% ------------------------------------------------------------------------- random-ranges [41] 100.0% [50] 200(1.0%) 200(1.0%) build--> ...act/private/arrow-val-first.rkt:1214:0 ------------------------------------------------------------------------- ...ow-val-first.rkt:486:18 [42] 100.0% [51] 200(1.0%) 0(0.0%) ??? ...aster-eval-prog/src/core/alt-table.rkt:49:0 argmin [55] 100.0% ------------------------------------------------------------------------- atab-add-altns [49] 5.1% for-loop [43] 94.9% [52] 4026(19.8%) 0(0.0%) atab-add-altn ...prog/src/core/alt-table.rkt:194:0 errors [64] 95.1% override-at-pnts [63] 4.9% ------------------------------------------------------------------------- egraph-run [44] 100.0% [53] 1020(5.0%) 0(0.0%) egraph-add-exprs ...gg-herbie-linux/main.rkt:111:0 ??? [58] 100.0% ------------------------------------------------------------------------- taylor-alt [45] 100.0% [54] 600(2.9%) 0(0.0%) for-loop ...aster-eval-prog/src/mainloop.rkt:152:6 ??? [84] 100.0% ------------------------------------------------------------------------- ??? [51] 50.0% baseline-error [13] 50.0% [55] 400(2.0%) 0(0.0%) argmin ...acket-7.5/collects/racket/list.rkt:765:0 ??? [61] 50.0% ??? [60] 50.0% ------------------------------------------------------------------------- loop [48] 100.0% [56] 206(1.0%) 0(0.0%) for-loop ...r-eval-prog/src/core/regimes.rkt:279:4 for-loop [59] 100.0% ------------------------------------------------------------------------- ??? [46] 100.0% [57] 200(1.0%) 0(0.0%) generic-sort/key .../racket/private/sort.rkt:156:2 copying-mergesort [62] 100.0% ------------------------------------------------------------------------- egraph-add-exprs [53] 100.0% [58] 1020(5.0%) 0(0.0%) ??? ...aster-eval-prog/src/core/simplify.rkt:102:6 for-loop [65] 100.0% ------------------------------------------------------------------------- for-loop [56] 100.0% [59] 206(1.0%) 0(0.0%) for-loop ...r-eval-prog/src/core/regimes.rkt:283:8 ??? [68] 100.0% ------------------------------------------------------------------------- argmin [55] 100.0% [60] 200(1.0%) 0(0.0%) ??? ...ie/faster-eval-prog/src/mainloop.rkt:114:10 errors [64] 100.0% ------------------------------------------------------------------------- argmin [55] 100.0% [61] 200(1.0%) 0(0.0%) ??? ...rbie/faster-eval-prog/src/points.rkt:182:27 eval-errors [66] 100.0% ------------------------------------------------------------------------- generic-sort/key [57] 100.0% [62] 200(1.0%) 0(0.0%) copying-mergesort ...racket/private/sort.rkt:129:8 loop [67] 100.0% ------------------------------------------------------------------------- atab-add-altn [52] 100.0% [63] 198(1.0%) 0(0.0%) override-at-pnts ...g/src/core/alt-table.rkt:129:0 for-loop [69] 100.0% ------------------------------------------------------------------------- ??? [60] 2.3% for-loop [34] 6.9% .../more-scheme.rkt:261:28 [8] 21.1% for-loop [43] 25.5% atab-add-altn [52] 44.2% [64] 8662(42.6%) 0(0.0%) errors ...ie/faster-eval-prog/src/points.rkt:190:0 for-loop [70] 100.0% ------------------------------------------------------------------------- ??? [58] 100.0% [65] 1020(5.0%) 1020(5.0%) for-loop ...-eval-prog/src/core/simplify.rkt:121:2 ------------------------------------------------------------------------- ??? [61] 25.0% .../more-scheme.rkt:261:28 [8] 75.0% [66] 800(3.9%) 0(0.0%) eval-errors ...ster-eval-prog/src/points.rkt:168:0 for-loop [71] 100.0% ------------------------------------------------------------------------- copying-mergesort [62] 49.8% ??? [46] 50.2% [67] 402(2.0%) 0(0.0%) loop ...7.5/collects/racket/private/sort.rkt:96:12 ??? [72] 100.0% ------------------------------------------------------------------------- for-loop [59] 100.0% [68] 206(1.0%) 206(1.0%) ??? ...cket-7.5/collects/racket/function.rkt:93:12 ------------------------------------------------------------------------- override-at-pnts [63] 100.0% [69] 198(1.0%) 198(1.0%) for-loop ...val-prog/src/core/alt-table.rkt:130:22 ------------------------------------------------------------------------- errors [64] 100.0% [70] 8662(42.6%) 614(3.0%) for-loop .../faster-eval-prog/src/points.rkt:192:2 .../more-scheme.rkt:261:28 [8] 92.9% ------------------------------------------------------------------------- eval-errors [66] 100.0% [71] 800(3.9%) 200(1.0%) for-loop .../faster-eval-prog/src/points.rkt:170:2 ??? [72] 75.0% ------------------------------------------------------------------------- loop [67] 2.5% for-loop [71] 3.8% loop [19] 3.8% loop [20] 11.4% ??? [24] 29.1% .../more-scheme.rkt:261:28 [8] 49.4% [72] 15894(78.1%) 600(2.9%) ??? ...rbie/faster-eval-prog/src/programs.rkt:97:2 hash-ref! [73] 86.0% for-loop [74] 7.6% ??? [77] 2.5% ------------------------------------------------------------------------- localize-error [36] 0.7% loop [6] 0.9% ??? [84] 1.4% loop [5] 1.4% map [82] 2.0% ??? [72] 37.4% ??? [75] 55.7% [73] 14682(72.1%) 6428(31.6%) hash-ref! .../racket/private/more-scheme.rkt:376:2 ??? [75] 67.9% ??? [77] 4.8% ??? [76] 4.2% parse-loop15 [80] 2.5% ??? [79] 1.4% f40 [78] 1.4% taylor [3] 0.9% ??? [84] 0.6% ??? [81] 0.3% ------------------------------------------------------------------------- ??? [72] 100.0% [74] 1214(6.0%) 1006(4.9%) for-loop ...aster-eval-prog/src/programs.rkt:100:6 return/no-unsupplied [85] 17.1% ------------------------------------------------------------------------- hash-ref! [73] 100.0% [75] 12674(62.3%) 802(3.9%) ??? ...ie/faster-eval-prog/src/programs.rkt:105:18 hash-ref! [73] 85.9% table-ref [83] 8.0% constant? [86] 1.2% variable? [88] 0.6% ------------------------------------------------------------------------- hash-ref! [73] 100.0% [76] 2014(9.9%) 1202(5.9%) ??? ...ster-eval-prog/src/syntax/syntax.rkt:214:17 return/no-unsupplied [85] 30.1% ??? [91] 10.2% ------------------------------------------------------------------------- ??? [72] 28.6% hash-ref! [73] 71.4% [77] 1412(6.9%) 404(2.0%) ??? ...tract/private/arrow-higher-order.rkt:379:33 ival-div [87] 28.5% ival-atan2 [89] 28.3% ival-log [90] 14.6% ------------------------------------------------------------------------- hash-ref! [73] 44.0% map [82] 56.0% [78] 600(2.9%) 0(0.0%) f40 (unknown source) map [82] 100.0% ------------------------------------------------------------------------- hash-ref! [73] 100.0% [79] 600(2.9%) 0(0.0%) ??? .../faster-eval-prog/src/core/taylor.rkt:67:15 ??? [84] 100.0% ------------------------------------------------------------------------- hash-ref! [73] 100.0% [80] 406(2.0%) 0(0.0%) parse-loop15 (unknown source) map [82] 100.0% ------------------------------------------------------------------------- hash-ref! [73] 100.0% [81] 206(1.0%) 0(0.0%) ??? ...aster-eval-prog/src/core/localize.rkt:17:13 repeat [92] 100.0% ------------------------------------------------------------------------- parse-loop15 [80] 15.6% f40 [78] 23.0% for-loop [15] 61.5% [82] 2610(12.8%) 0(0.0%) map ...t-7.5/collects/racket/private/map.rkt:35:13 ??? [24] 38.5% loop [94] 23.0% hash-ref! [73] 13.0% f40 [78] 12.9% ??? [98] 7.4% make-multiplication-node [99] 2.8% loop [101] 2.6% ------------------------------------------------------------------------- ??? [75] 100.0% [83] 2216(10.9%) 2016(9.9%) table-ref .../faster-eval-prog/src/common.rkt:33:0 assoc-iterate-first [102] 9.0% ------------------------------------------------------------------------- ??? [79] 16.4% location-do [95] 16.4% for-loop [54] 16.4% for-loop [47] 17.7% hash-ref! [73] 33.2% [84] 1222(6.0%) 622(3.1%) ??? ...tract/private/arrow-higher-order.rkt:375:33 location-do [95] 16.4% approximate9 [2] 16.4% hash-ref! [73] 16.4% ------------------------------------------------------------------------- for-loop [74] 25.6% ??? [76] 74.4% [85] 814(4.0%) 408(2.0%) return/no-unsupplied ...ct/private/arr-i.rkt:566:0 filter [96] 49.9% ------------------------------------------------------------------------- ??? [75] 100.0% [86] 402(2.0%) 0(0.0%) constant? ...eval-prog/src/syntax/syntax.rkt:726:0 value? [93] 100.0% ------------------------------------------------------------------------- ??? [77] 100.0% [87] 402(2.0%) 202(1.0%) ival-div ...er-eval-prog/src/biginterval.rkt:156:0 bfdiv [97] 49.8% ------------------------------------------------------------------------- ??? [75] 100.0% [88] 400(2.0%) 0(0.0%) variable? ...eval-prog/src/syntax/syntax.rkt:729:0 value? [93] 100.0% ------------------------------------------------------------------------- ??? [77] 100.0% [89] 400(2.0%) 200(1.0%) ival-atan2 ...-eval-prog/src/biginterval.rkt:332:0 bfdiv [97] 50.0% ------------------------------------------------------------------------- ??? [77] 100.0% [90] 206(1.0%) 206(1.0%) ival-log ...er-eval-prog/src/biginterval.rkt:202:0 ------------------------------------------------------------------------- ??? [76] 100.0% [91] 206(1.0%) 206(1.0%) ??? ...ects/racket/contract/private/guts.rkt:770:8 ------------------------------------------------------------------------- ??? [81] 100.0% [92] 206(1.0%) 0(0.0%) repeat ...ster-eval-prog/src/core/localize.rkt:8:0 for-loop [100] 100.0% ------------------------------------------------------------------------- variable? [88] 49.9% constant? [86] 50.1% [93] 802(3.9%) 802(3.9%) value? ...ster-eval-prog/src/syntax/types.rkt:18:0 ------------------------------------------------------------------------- map [82] 100.0% [94] 600(2.9%) 0(0.0%) loop ...-7.5/collects/racket/private/map.rkt:40:19 ??? [24] 100.0% ------------------------------------------------------------------------- ??? [84] 48.8% location-do [95] 51.2% [95] 600(2.9%) 0(0.0%) location-do ...ter-eval-prog/src/programs.rkt:62:0 location-do [95] 51.2% ??? [84] 48.8% ------------------------------------------------------------------------- return/no-unsupplied [85] 100.0% [96] 406(2.0%) 406(2.0%) filter ...5/collects/racket/private/list.rkt:256:2 ------------------------------------------------------------------------- ival-div [87] 50.0% ival-atan2 [89] 50.0% [97] 400(2.0%) 0(0.0%) bfdiv ...-lib/math/private/bigfloat/mpfr.rkt:835:4 new-mpfr [103] 100.0% ------------------------------------------------------------------------- map [82] 100.0% [98] 384(1.9%) 384(1.9%) ??? ...e/faster-eval-prog/src/core/reduce.rkt:57:5 ------------------------------------------------------------------------- map [82] 100.0% [99] 216(1.1%) 0(0.0%) make-multiplication-node .../core/reduce.rkt:232:0 make-multiplication-subnode [104]100.0% ------------------------------------------------------------------------- repeat [92] 100.0% [100] 206(1.0%) 0(0.0%) for-loop ...er-eval-prog/src/core/localize.rkt:9:2 ??? [105] 100.0% ------------------------------------------------------------------------- map [82] 2.3% loop [101] 97.7% [101] 200(1.0%) 200(1.0%) loop ...-7.5/collects/racket/private/map.rkt:54:19 loop [101] 97.7% ------------------------------------------------------------------------- table-ref [83] 100.0% [102] 200(1.0%) 200(1.0%) assoc-iterate-first ...cket/private/dict.rkt:166:0 ------------------------------------------------------------------------- bfdiv [97] 100.0% [103] 400(2.0%) 400(2.0%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:348:0 ------------------------------------------------------------------------- make-multiplication-node [99] 100.0% [104] 216(1.1%) 216(1.1%) make-multiplication-subnode ...re/reduce.rkt:242:0 ------------------------------------------------------------------------- for-loop [100] 100.0% [105] 206(1.0%) 206(1.0%) ??? ...7.5/collects/racket/private/for.rkt:1236:14 -------------------------------------------------------------------------