Profiling results ----------------- Total cpu time observed: 11628ms (out of 11812ms) Number of samples taken: 63 (once every 185ms) (Hiding functions with self<1.0% and local<2.0%: 1 of 99 hidden) ======================================================================= Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ======================================================================= [1] 11628(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [6] 100.0% ----------------------------------------------------------------------- matcher [56] 100.0% [2] 960(8.3%) 0(0.0%) ??? ...aster-eval-prog/src/core/matcher.rkt:127:11 for-loop [7] 100.0% ----------------------------------------------------------------------- hash-ref! [64] 100.0% [3] 434(3.7%) 0(0.0%) ??? .../faster-eval-prog/src/core/taylor.rkt:67:15 ??? [61] 100.0% ----------------------------------------------------------------------- ??? [61] 100.0% [4] 434(3.7%) 0(0.0%) approximate9 ...eval-prog/src/core/taylor.rkt:11:0 loop [8] 100.0% ----------------------------------------------------------------------- hash-ref! [64] 100.0% [5] 434(3.7%) 0(0.0%) ??? ...faster-eval-prog/src/core/taylor.rkt:290:26 for-loop [9] 100.0% ----------------------------------------------------------------------- ??? [1] 100.0% [6] 11628(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [10] 100.0% ----------------------------------------------------------------------- ??? [2] 100.0% [7] 960(8.3%) 0(0.0%) for-loop ...-eval-prog/src/core/matcher.rkt:129:15 matcher [56] 100.0% ----------------------------------------------------------------------- approximate9 [4] 100.0% [8] 434(3.7%) 0(0.0%) loop .../faster-eval-prog/src/core/taylor.rkt:86:4 hash-ref! [64] 100.0% ----------------------------------------------------------------------- ??? [5] 100.0% [9] 434(3.7%) 0(0.0%) for-loop ...r-eval-prog/src/core/taylor.rkt:290:52 ??? [11] 100.0% ----------------------------------------------------------------------- profile-thunk16 [6] 100.0% [10] 11628(100.0%) 0(0.0%) run ...et-7.5/share/pkgs/profile-lib/main.rkt:39:2 .../more-scheme.rkt:261:28 [12] 100.0% ----------------------------------------------------------------------- for-loop [9] 100.0% [11] 434(3.7%) 0(0.0%) ??? ...faster-eval-prog/src/core/taylor.rkt:293:19 hash-ref! [64] 100.0% ----------------------------------------------------------------------- for-loop [98] 25.6% run [10] 74.4% [12] 11628(100.0%) 338(2.9%) .../more-scheme.rkt:261:28 (unknown source) run-improve47 [13] 40.2% ??? [45] 24.9% prepare-points-intervals [14] 11.4% errors [96] 7.5% oracle-error [15] 7.4% baseline-error [16] 1.7% ??? [72] 1.6% get-final-combination [17] 1.6% eval-errors [39] 1.4% ----------------------------------------------------------------------- .../more-scheme.rkt:261:28 [12] 100.0% [13] 6948(59.8%) 0(0.0%) run-improve47 ...-eval-prog/src/mainloop.rkt:346:0 for-loop [18] 100.0% ----------------------------------------------------------------------- .../more-scheme.rkt:261:28 [12] 100.0% [14] 1328(11.4%) 0(0.0%) prepare-points-intervals ...g/src/points.rkt:120:0 loop [19] 100.0% ----------------------------------------------------------------------- .../more-scheme.rkt:261:28 [12] 100.0% [15] 866(7.4%) 0(0.0%) oracle-error ...ter-eval-prog/src/points.rkt:177:0 for-loop [20] 100.0% ----------------------------------------------------------------------- .../more-scheme.rkt:261:28 [12] 100.0% [16] 198(1.7%) 0(0.0%) baseline-error ...r-eval-prog/src/points.rkt:181:0 argmin [25] 100.0% ----------------------------------------------------------------------- .../more-scheme.rkt:261:28 [12] 100.0% [17] 184(1.6%) 0(0.0%) get-final-combination ...og/src/mainloop.rkt:371:0 infer-splitpoints [21] 100.0% ----------------------------------------------------------------------- run-improve47 [13] 100.0% [18] 6948(59.8%) 0(0.0%) for-loop ...aster-eval-prog/src/mainloop.rkt:365:4 run-iter! [22] 100.0% ----------------------------------------------------------------------- prepare-points-intervals [14] 100.0% [19] 1328(11.4%) 0(0.0%) loop ...rbie/faster-eval-prog/src/points.rkt:132:4 loop [23] 62.7% ??? [24] 37.3% ----------------------------------------------------------------------- oracle-error [15] 100.0% [20] 866(7.4%) 0(0.0%) for-loop .../faster-eval-prog/src/points.rkt:178:2 loop [26] 40.9% map [74] 40.0% argmin [25] 19.2% ----------------------------------------------------------------------- get-final-combination [17] 100.0% [21] 184(1.6%) 0(0.0%) infer-splitpoints ...rog/src/core/regimes.rkt:33:0 for-loop [27] 100.0% ----------------------------------------------------------------------- for-loop [18] 100.0% [22] 6948(59.8%) 0(0.0%) run-iter! ...ster-eval-prog/src/mainloop.rkt:327:0 finalize-iter! [28] 70.6% gen-rewrites! [29] 13.8% simplify! [30] 9.3% gen-series! [32] 6.2% ----------------------------------------------------------------------- loop [19] 100.0% [23] 832(7.2%) 0(0.0%) loop ...erbie/faster-eval-prog/src/points.rkt:79:2 ??? [45] 100.0% ----------------------------------------------------------------------- loop [19] 100.0% [24] 496(4.3%) 0(0.0%) ??? ...erbie/faster-eval-prog/src/points.rkt:113:6 sample-multi-bounded [31] 100.0% ----------------------------------------------------------------------- for-loop [20] 45.6% baseline-error [16] 54.4% [25] 364(3.1%) 166(1.4%) argmin ...acket-7.5/collects/racket/list.rkt:765:0 ??? [33] 54.4% ----------------------------------------------------------------------- for-loop [20] 100.0% [26] 354(3.0%) 0(0.0%) loop (unknown source) ??? [84] 100.0% ----------------------------------------------------------------------- infer-splitpoints [21] 100.0% [27] 184(1.6%) 0(0.0%) for-loop ...er-eval-prog/src/core/regimes.rkt:45:6 option-on-expr [34] 100.0% ----------------------------------------------------------------------- run-iter! [22] 100.0% [28] 4908(42.2%) 0(0.0%) finalize-iter! ...eval-prog/src/mainloop.rkt:278:0 ??? [82] 100.0% ----------------------------------------------------------------------- run-iter! [22] 100.0% [29] 960(8.3%) 0(0.0%) gen-rewrites! ...-eval-prog/src/mainloop.rkt:183:0 for-loop [35] 100.0% ----------------------------------------------------------------------- run-iter! [22] 100.0% [30] 646(5.6%) 0(0.0%) simplify! ...ster-eval-prog/src/mainloop.rkt:224:0 ??? [36] 100.0% ----------------------------------------------------------------------- ??? [24] 100.0% [31] 496(4.3%) 0(0.0%) sample-multi-bounded ...l-prog/src/points.rkt:14:0 random-ranges [37] 100.0% ----------------------------------------------------------------------- run-iter! [22] 100.0% [32] 434(3.7%) 0(0.0%) gen-series! ...er-eval-prog/src/mainloop.rkt:160:0 for-loop [38] 100.0% ----------------------------------------------------------------------- argmin [25] 100.0% [33] 198(1.7%) 0(0.0%) ??? ...rbie/faster-eval-prog/src/points.rkt:182:27 eval-errors [39] 100.0% ----------------------------------------------------------------------- for-loop [27] 100.0% [34] 184(1.6%) 0(0.0%) option-on-expr ...-prog/src/core/regimes.rkt:124:0 ??? [78] 100.0% ----------------------------------------------------------------------- gen-rewrites! [29] 100.0% [35] 960(8.3%) 0(0.0%) for-loop ...ster-eval-prog/src/mainloop.rkt:191:11 rewrite-expression-head24 [40] 100.0% ----------------------------------------------------------------------- simplify! [30] 100.0% [36] 646(5.6%) 0(0.0%) ??? ...faster-eval-prog/src/core/simplify.rkt:42:0 egraph-run [41] 100.0% ----------------------------------------------------------------------- sample-multi-bounded [31] 100.0% [37] 496(4.3%) 0(0.0%) random-ranges ...er-eval-prog/src/common.rkt:164:0 cons/c [42] 100.0% ----------------------------------------------------------------------- gen-series! [32] 100.0% [38] 434(3.7%) 0(0.0%) for-loop ...aster-eval-prog/src/mainloop.rkt:167:7 taylor-alt [43] 100.0% ----------------------------------------------------------------------- .../more-scheme.rkt:261:28 [12] 45.9% ??? [33] 54.1% [39] 366(3.1%) 0(0.0%) eval-errors ...ster-eval-prog/src/points.rkt:168:0 for-loop [44] 100.0% ----------------------------------------------------------------------- for-loop [35] 100.0% [40] 960(8.3%) 0(0.0%) rewrite-expression-head24 ...core/matcher.rkt:85:0 rewriter [46] 100.0% ----------------------------------------------------------------------- ??? [36] 100.0% [41] 646(5.6%) 0(0.0%) egraph-run .../pkgs/egg-herbie-linux/main.rkt:49:0 egraph-add-exprs [47] 100.0% ----------------------------------------------------------------------- random-ranges [37] 100.0% [42] 496(4.3%) 0(0.0%) cons/c .../racket/contract/private/guts.rkt:500:15 coerce-contract [49] 66.1% flat-contract? [51] 33.9% ----------------------------------------------------------------------- for-loop [38] 100.0% [43] 434(3.7%) 0(0.0%) taylor-alt ...ter-eval-prog/src/mainloop.rkt:146:0 for-loop [48] 100.0% ----------------------------------------------------------------------- eval-errors [39] 100.0% [44] 366(3.1%) 0(0.0%) for-loop .../faster-eval-prog/src/points.rkt:170:2 ??? [50] 54.1% ??? [45] 45.9% ----------------------------------------------------------------------- for-loop [44] 2.2% ??? [84] 9.4% loop [23] 11.1% .../more-scheme.rkt:261:28 [12] 77.3% [45] 7484(64.4%) 202(1.7%) ??? ...rbie/faster-eval-prog/src/programs.rkt:97:2 hash-ref! [64] 82.5% for-loop [52] 14.8% ----------------------------------------------------------------------- rewrite-expression-head24 [40] 28.1% matcher [56] 71.9% [46] 960(8.3%) 194(1.7%) rewriter ...er-eval-prog/src/core/matcher.rkt:87:2 for-loop [53] 96.0% ----------------------------------------------------------------------- egraph-run [41] 100.0% [47] 646(5.6%) 0(0.0%) egraph-add-exprs ...gg-herbie-linux/main.rkt:111:0 ??? [54] 100.0% ----------------------------------------------------------------------- taylor-alt [43] 100.0% [48] 434(3.7%) 0(0.0%) for-loop ...aster-eval-prog/src/mainloop.rkt:152:6 ??? [61] 100.0% ----------------------------------------------------------------------- cons/c [42] 100.0% [49] 328(2.8%) 0(0.0%) coerce-contract ...contract/private/guts.rkt:307:0 coerce-contract/f [55] 100.0% ----------------------------------------------------------------------- for-loop [44] 100.0% [50] 198(1.7%) 198(1.7%) ??? ...cket-7.5/collects/racket/function.rkt:93:12 ----------------------------------------------------------------------- cons/c [42] 100.0% [51] 168(1.4%) 168(1.4%) flat-contract? .../contract/private/guts.rkt:129:0 ----------------------------------------------------------------------- ??? [45] 100.0% [52] 1104(9.5%) 786(6.8%) for-loop ...aster-eval-prog/src/programs.rkt:100:6 real->precision [60] 28.8% ----------------------------------------------------------------------- rewriter [46] 100.0% [53] 960(8.3%) 0(0.0%) for-loop ...er-eval-prog/src/core/matcher.rkt:89:4 matcher [56] 84.8% for-loop [58] 15.2% ----------------------------------------------------------------------- egraph-add-exprs [47] 100.0% [54] 646(5.6%) 0(0.0%) ??? ...aster-eval-prog/src/core/simplify.rkt:102:6 for-loop [57] 100.0% ----------------------------------------------------------------------- coerce-contract [49] 100.0% [55] 328(2.8%) 0(0.0%) coerce-contract/f ...ntract/private/guts.rkt:372:0 coerce-simple-value [59] 100.0% ----------------------------------------------------------------------- for-loop [7] 48.9% for-loop [53] 51.1% [56] 960(8.3%) 0(0.0%) matcher ...er-eval-prog/src/core/matcher.rkt:115:2 ??? [2] 48.9% rewriter [46] 46.4% variable? [77] 2.5% constant? [75] 2.2% ----------------------------------------------------------------------- ??? [54] 100.0% [57] 646(5.6%) 646(5.6%) for-loop ...-eval-prog/src/core/simplify.rkt:121:2 ----------------------------------------------------------------------- for-loop [53] 100.0% [58] 386(3.3%) 0(0.0%) for-loop ...er-eval-prog/src/core/matcher.rkt:96:8 fix-up-variables [62] 100.0% ----------------------------------------------------------------------- coerce-contract/f [55] 100.0% [59] 328(2.8%) 328(2.8%) coerce-simple-value ...ract/private/guts.rkt:385:0 ----------------------------------------------------------------------- for-loop [52] 100.0% [60] 318(2.7%) 0(0.0%) real->precision ...val-prog/src/programs.rkt:89:11 ??? [61] 52.2% ??? [63] 47.8% ----------------------------------------------------------------------- ??? [3] 24.1% location-do [65] 24.1% for-loop [48] 24.1% real->precision [60] 27.7% [61] 600(5.2%) 166(1.4%) ??? ...tract/private/arrow-higher-order.rkt:375:33 location-do [65] 24.1% approximate9 [4] 24.1% hash-ref! [64] 24.1% ----------------------------------------------------------------------- for-loop [58] 100.0% [62] 386(3.3%) 0(0.0%) fix-up-variables ...rog/src/core/matcher.rkt:108:2 pattern-match [66] 50.8% for-loop [67] 49.2% ----------------------------------------------------------------------- real->precision [60] 100.0% [63] 152(1.3%) 0(0.0%) ??? ...ects/racket/contract/private/guts.rkt:770:8 bigvalue? [68] 100.0% ----------------------------------------------------------------------- ??? [11] 1.6% ??? [61] 1.6% loop [8] 1.6% ??? [45] 40.5% ??? [69] 52.9% [64] 6612(56.9%) 3296(28.3%) hash-ref! .../racket/private/more-scheme.rkt:376:2 ??? [69] 67.2% ??? [71] 5.6% ??? [72] 2.3% ??? [3] 1.6% ??? [5] 1.6% f40 [70] 1.6% ----------------------------------------------------------------------- ??? [61] 100.0% [65] 434(3.7%) 0(0.0%) location-do ...ter-eval-prog/src/programs.rkt:62:0 ??? [61] 100.0% ----------------------------------------------------------------------- fix-up-variables [62] 100.0% [66] 196(1.7%) 196(1.7%) pattern-match ...al-prog/src/core/matcher.rkt:29:0 ----------------------------------------------------------------------- fix-up-variables [62] 100.0% [67] 190(1.6%) 0(0.0%) for-loop ...er-eval-prog/src/core/matcher.rkt:41:9 for-loop [73] 100.0% ----------------------------------------------------------------------- ??? [63] 100.0% [68] 152(1.3%) 152(1.3%) bigvalue? ...r-eval-prog/src/syntax/types.rkt:19:0 ----------------------------------------------------------------------- hash-ref! [64] 100.0% [69] 5466(47.0%) 876(7.5%) ??? ...ie/faster-eval-prog/src/programs.rkt:105:18 hash-ref! [64] 82.8% table-ref [76] 9.0% constant? [75] 2.6% variable? [77] 1.1% ----------------------------------------------------------------------- hash-ref! [64] 41.0% map [74] 59.0% [70] 434(3.7%) 0(0.0%) f40 (unknown source) map [74] 100.0% ----------------------------------------------------------------------- hash-ref! [64] 100.0% [71] 368(3.2%) 186(1.6%) ??? ...ster-eval-prog/src/syntax/syntax.rkt:214:17 return/no-unsupplied [80] 49.5% ----------------------------------------------------------------------- hash-ref! [64] 45.0% .../more-scheme.rkt:261:28 [12] 55.0% [72] 338(2.9%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:379:33 ??? [78] 55.0% ival-div [81] 45.0% ----------------------------------------------------------------------- for-loop [67] 100.0% [73] 190(1.6%) 0(0.0%) for-loop ...er-eval-prog/src/core/matcher.rkt:26:9 core457 [79] 100.0% ----------------------------------------------------------------------- for-loop [20] 44.4% f40 [70] 55.6% [74] 780(6.7%) 0(0.0%) map ...t-7.5/collects/racket/private/map.rkt:35:13 ??? [84] 44.4% f40 [70] 32.8% simplify* [86] 12.8% make-multiplication-node [85] 10.0% ----------------------------------------------------------------------- matcher [56] 24.4% ??? [69] 75.6% [75] 772(6.6%) 0(0.0%) constant? ...eval-prog/src/syntax/syntax.rkt:726:0 value? [83] 100.0% ----------------------------------------------------------------------- ??? [69] 100.0% [76] 716(6.2%) 532(4.6%) table-ref .../faster-eval-prog/src/common.rkt:33:0 assoc-iterate-first [88] 25.7% ----------------------------------------------------------------------- ??? [69] 49.2% matcher [56] 50.8% [77] 378(3.3%) 0(0.0%) variable? ...eval-prog/src/syntax/syntax.rkt:729:0 ??? [82] 50.8% value? [83] 49.2% ----------------------------------------------------------------------- for-loop [87] 25.1% ??? [72] 25.1% option-on-expr [34] 49.7% [78] 370(3.2%) 370(3.2%) ??? ...cts/racket/contract/private/list.rkt:209:10 for-loop [87] 25.1% ----------------------------------------------------------------------- for-loop [73] 100.0% [79] 190(1.6%) 190(1.6%) core457 ...acket/contract/private/arr-i.rkt:976:19 ----------------------------------------------------------------------- ??? [71] 100.0% [80] 182(1.6%) 182(1.6%) return/no-unsupplied ...ct/private/arr-i.rkt:566:0 ----------------------------------------------------------------------- ??? [72] 100.0% [81] 152(1.3%) 0(0.0%) ival-div ...er-eval-prog/src/biginterval.rkt:156:0 bfdiv [89] 100.0% ----------------------------------------------------------------------- variable? [77] 3.8% finalize-iter! [28] 96.2% [82] 5100(43.9%) 192(1.7%) ??? ...contract/private/arrow-val-first.rkt:486:18 for-loop [90] 96.2% ----------------------------------------------------------------------- variable? [77] 19.4% constant? [75] 80.6% [83] 958(8.2%) 958(8.2%) value? ...ster-eval-prog/src/syntax/types.rkt:18:0 ----------------------------------------------------------------------- map [74] 49.4% loop [26] 50.6% [84] 700(6.0%) 0(0.0%) ??? ...rbie/faster-eval-prog/src/points.rkt:179:26 ??? [45] 100.0% ----------------------------------------------------------------------- map [74] 100.0% [85] 234(2.0%) 0(0.0%) make-multiplication-node .../core/reduce.rkt:232:0 make-multiplication-subnode [91]100.0% ----------------------------------------------------------------------- map [74] 100.0% [86] 200(1.7%) 200(1.7%) simplify* ...er-eval-prog/src/core/reduce.rkt:29:0 ----------------------------------------------------------------------- ??? [78] 100.0% [87] 186(1.6%) 0(0.0%) for-loop ...acket/contract/private/list.rkt:212:15 ??? [78] 100.0% ----------------------------------------------------------------------- table-ref [76] 100.0% [88] 184(1.6%) 184(1.6%) assoc-iterate-first ...cket/private/dict.rkt:166:0 ----------------------------------------------------------------------- ival-div [81] 100.0% [89] 152(1.3%) 0(0.0%) bfdiv ...-lib/math/private/bigfloat/mpfr.rkt:835:4 new-mpfr [92] 100.0% ----------------------------------------------------------------------- ??? [82] 100.0% [90] 4908(42.2%) 0(0.0%) for-loop ...-eval-prog/src/core/alt-table.rkt:46:2 errors [96] 50.2% atab-add-altn [93] 46.0% loop [95] 3.7% ----------------------------------------------------------------------- make-multiplication-node [85] 100.0% [91] 234(2.0%) 0(0.0%) make-multiplication-subnode ...re/reduce.rkt:242:0 for-loop [94] 100.0% ----------------------------------------------------------------------- bfdiv [89] 100.0% [92] 152(1.3%) 152(1.3%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:348:0 ----------------------------------------------------------------------- for-loop [90] 100.0% [93] 2258(19.4%) 0(0.0%) atab-add-altn ...prog/src/core/alt-table.rkt:194:0 errors [96] 100.0% ----------------------------------------------------------------------- make-multiplication-subnode [91]100.0% [94] 234(2.0%) 0(0.0%) for-loop ...er-eval-prog/src/core/reduce.rkt:244:3 partition [97] 100.0% ----------------------------------------------------------------------- for-loop [90] 100.0% [95] 184(1.6%) 184(1.6%) loop ...ter-eval-prog/src/core/alt-table.rkt:168:2 ----------------------------------------------------------------------- .../more-scheme.rkt:261:28 [12] 25.0% atab-add-altn [93] 35.9% for-loop [90] 39.2% [96] 6296(54.1%) 0(0.0%) errors ...ie/faster-eval-prog/src/points.rkt:190:0 for-loop [98] 100.0% ----------------------------------------------------------------------- for-loop [94] 100.0% [97] 234(2.0%) 234(2.0%) partition ...et-7.5/collects/racket/list.rkt:555:0 ----------------------------------------------------------------------- errors [96] 100.0% [98] 6296(54.1%) 352(3.0%) for-loop .../faster-eval-prog/src/points.rkt:192:2 .../more-scheme.rkt:261:28 [12] 94.4% -----------------------------------------------------------------------