Profiling results ----------------- Total cpu time observed: 14148ms (out of 14520ms) Number of samples taken: 42 (once every 337ms) =================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee =================================================================== ??? [4] 100.0% [1] 14148(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [3] 100.0% ------------------------------------------------------------------- inductor [34] 100.0% [2] 310(2.2%) 0(0.0%) variable? ...e/develop/src/syntax/syntax.rkt:716:0 ??? [21] 100.0% ------------------------------------------------------------------- profile-thunk16 [1] 100.0% [3] 14148(100.0%) 0(0.0%) run ...racket/share/pkgs/profile-lib/main.rkt:39:2 ??? [4] 100.0% ------------------------------------------------------------------- run [3] 50.0% [4] 14148(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 50.0% loop [5] 27.4% run-improve39 [6] 19.8% eval-errors [31] 1.8% oracle-error [7] 0.9% ------------------------------------------------------------------- ??? [4] 100.0% [5] 7756(54.8%) 300(2.1%) loop ...htlies/herbie/develop/src/points.rkt:242:2 make-exacts-intervals [8] 92.3% for-loop [10] 3.9% ------------------------------------------------------------------- ??? [4] 100.0% [6] 5616(39.7%) 0(0.0%) run-improve39 ...ie/develop/src/mainloop.rkt:322:0 for-loop [9] 94.8% for-loop [11] 5.2% ------------------------------------------------------------------- ??? [4] 100.0% [7] 268(1.9%) 0(0.0%) oracle-error ...erbie/develop/src/points.rkt:311:0 for-loop [12] 100.0% ------------------------------------------------------------------- loop [5] 100.0% [8] 7156(50.6%) 0(0.0%) make-exacts-intervals ...elop/src/points.rkt:183:0 for-loop [13] 95.7% eval-prog [32] 4.3% ------------------------------------------------------------------- run-improve39 [6] 100.0% [9] 5324(37.6%) 0(0.0%) for-loop ...herbie/develop/src/mainloop.rkt:339:10 run-iter! [14] 100.0% ------------------------------------------------------------------- loop [5] 100.0% [10] 300(2.1%) 0(0.0%) for-loop ...s/herbie/develop/src/points.rkt:257:19 for-loop [15] 100.0% ------------------------------------------------------------------- run-improve39 [6] 100.0% [11] 292(2.1%) 0(0.0%) for-loop ...herbie/develop/src/mainloop.rkt:333:19 ??? [45] 100.0% ------------------------------------------------------------------- oracle-error [7] 100.0% [12] 268(1.9%) 268(1.9%) for-loop ...es/herbie/develop/src/points.rkt:312:2 ------------------------------------------------------------------- make-exacts-intervals [8] 100.0% [13] 6846(48.4%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:186:2 loop [16] 95.9% ??? [41] 4.1% ------------------------------------------------------------------- for-loop [9] 100.0% [14] 5324(37.6%) 0(0.0%) run-iter! ...herbie/develop/src/mainloop.rkt:303:0 simplify! [17] 46.9% finalize-iter! [18] 40.8% gen-series! [19] 12.2% ------------------------------------------------------------------- for-loop [10] 100.0% [15] 300(2.1%) 300(2.1%) for-loop ...es/herbie/develop/src/points.rkt:234:4 ------------------------------------------------------------------- for-loop [13] 100.0% [16] 6568(46.4%) 304(2.1%) loop ...htlies/herbie/develop/src/points.rkt:168:2 ??? [36] 87.4% ??? [41] 7.9% ------------------------------------------------------------------- run-iter! [14] 100.0% [17] 2498(17.7%) 0(0.0%) simplify! ...herbie/develop/src/mainloop.rkt:206:0 for-loop [20] 100.0% ------------------------------------------------------------------- run-iter! [14] 100.0% [18] 2174(15.4%) 0(0.0%) finalize-iter! ...e/develop/src/mainloop.rkt:257:0 ??? [21] 100.0% ------------------------------------------------------------------- run-iter! [14] 100.0% [19] 652(4.6%) 0(0.0%) gen-series! ...rbie/develop/src/mainloop.rkt:152:0 for-loop [22] 100.0% ------------------------------------------------------------------- simplify! [17] 100.0% [20] 2498(17.7%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:213:6 for-loop [23] 100.0% ------------------------------------------------------------------- variable? [2] 7.1% finalize-iter! [18] 92.9% [21] 2174(15.4%) 310(2.2%) ??? ...contract/private/arrow-val-first.rkt:388:18 for-loop [24] 92.9% ------------------------------------------------------------------- gen-series! [19] 100.0% [22] 652(4.6%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:160:7 taylor-alt [25] 100.0% ------------------------------------------------------------------- for-loop [20] 100.0% [23] 2498(17.7%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:237:8 ??? [41] 100.0% ------------------------------------------------------------------- ??? [21] 100.0% [24] 2174(15.4%) 0(0.0%) for-loop ...ie/develop/src/core/alt-table.rkt:51:2 atab-add-altn [26] 85.6% loop [28] 14.4% ------------------------------------------------------------------- for-loop [22] 100.0% [25] 652(4.6%) 0(0.0%) taylor-alt ...erbie/develop/src/mainloop.rkt:138:0 for-loop [27] 100.0% ------------------------------------------------------------------- for-loop [24] 100.0% [26] 1862(13.2%) 0(0.0%) atab-add-altn ...elop/src/core/alt-table.rkt:210:0 best-and-tied-at-points [29] 50.1% override-at-pnts [30] 49.9% ------------------------------------------------------------------- taylor-alt [25] 100.0% [27] 652(4.6%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:144:6 ??? [41] 100.0% ------------------------------------------------------------------- for-loop [24] 100.0% [28] 312(2.2%) 0(0.0%) loop ...erbie/develop/src/core/alt-table.rkt:184:2 loop [67] 100.0% ------------------------------------------------------------------- atab-add-altn [26] 100.0% [29] 932(6.6%) 0(0.0%) best-and-tied-at-points ...ore/alt-table.rkt:122:0 eval-prog [32] 66.7% eval-errors [31] 33.3% ------------------------------------------------------------------- atab-add-altn [26] 100.0% [30] 930(6.6%) 0(0.0%) override-at-pnts ...p/src/core/alt-table.rkt:145:0 eval-errors [31] 66.9% eval-prog [32] 33.1% ------------------------------------------------------------------- best-and-tied-at-points [29] 21.5% ??? [4] 35.3% override-at-pnts [30] 43.2% [31] 1440(10.2%) 0(0.0%) eval-errors ...herbie/develop/src/points.rkt:292:0 for-loop [33] 100.0% ------------------------------------------------------------------- override-at-pnts [30] 24.8% make-exacts-intervals [8] 25.0% best-and-tied-at-points [29] 50.2% [32] 1240(8.8%) 0(0.0%) eval-prog ...herbie/develop/src/programs.rkt:108:0 inductor [34] 50.2% temp68_2 [35] 49.8% ------------------------------------------------------------------- eval-errors [31] 100.0% [33] 1440(10.2%) 310(2.2%) for-loop ...es/herbie/develop/src/points.rkt:294:2 ??? [36] 56.4% ulp-difference [38] 22.1% ------------------------------------------------------------------- eval-prog [32] 25.0% inductor [34] 75.0% [34] 622(4.4%) 0(0.0%) inductor .../herbie/develop/src/programs.rkt:113:4 inductor [34] 75.0% table-ref [39] 16.7% variable? [2] 8.3% ------------------------------------------------------------------- eval-prog [32] 100.0% [35] 618(4.4%) 0(0.0%) temp68_2 (unknown source) compile16 [37] 100.0% ------------------------------------------------------------------- for-loop [33] 12.4% loop [16] 87.6% [36] 6554(46.3%) 304(2.1%) ??? ...tlies/herbie/develop/src/programs.rkt:124:2 ??? [40] 74.6% ??? [43] 13.0% ??? [41] 7.8% ------------------------------------------------------------------- temp68_2 [35] 100.0% [37] 618(4.4%) 0(0.0%) compile16 (unknown source) temp74_0 [42] 100.0% ------------------------------------------------------------------- for-loop [33] 100.0% [38] 318(2.2%) 318(2.2%) ulp-difference ...erbie/develop/src/float.rkt:19:0 ------------------------------------------------------------------- inductor [34] 100.0% [39] 312(2.2%) 312(2.2%) table-ref ...es/herbie/develop/src/common.rkt:46:0 ------------------------------------------------------------------- ??? [36] 100.0% [40] 4890(34.6%) 0(0.0%) ??? ...acket/collects/racket/private/kw.rkt:592:14 ??? [43] 100.0% ------------------------------------------------------------------- for-loop [13] 6.2% for-loop [27] 7.3% ??? [36] 11.4% loop [16] 11.7% for-loop [23] 28.0% location-do [44] 35.3% [41] 4458(31.5%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:357:33 location-do [44] 35.3% ->flonum [46] 29.3% ??? [45] 24.6% approximate9 [47] 7.3% ??? [50] 3.4% ------------------------------------------------------------------- compile16 [37] 100.0% [42] 618(4.4%) 0(0.0%) temp74_0 (unknown source) expand-single [48] 50.2% compile-top9 [49] 49.8% ------------------------------------------------------------------- ??? [36] 14.8% ??? [40] 85.2% [43] 5742(40.6%) 280(2.0%) ??? ...tract/private/arrow-higher-order.rkt:361:33 ival-div [51] 69.5% ival-sub [53] 15.7% mk-ival [54] 10.0% ------------------------------------------------------------------- location-do [44] 39.7% ??? [41] 60.3% [44] 3150(22.3%) 0(0.0%) location-do ...erbie/develop/src/programs.rkt:89:0 ??? [41] 60.3% location-do [44] 39.7% ------------------------------------------------------------------- for-loop [11] 11.8% ??? [41] 88.2% [45] 2484(17.6%) 0(0.0%) ??? ...s/herbie/develop/src/core/simplify.rkt:31:0 iterate-egraph!7 [52] 100.0% ------------------------------------------------------------------- ??? [41] 100.0% [46] 1308(9.2%) 786(5.6%) ->flonum ...s/herbie/develop/src/programs.rkt:36:0 return/no-unsupplied [55] 39.9% ------------------------------------------------------------------- ??? [41] 100.0% [47] 652(4.6%) 0(0.0%) approximate9 ...e/develop/src/core/taylor.rkt:12:0 taylor [60] 53.1% for-loop [58] 46.9% ------------------------------------------------------------------- temp74_0 [42] 100.0% [48] 310(2.2%) 0(0.0%) expand-single (unknown source) expand-capturing-lifts [56] 100.0% ------------------------------------------------------------------- temp74_0 [42] 100.0% [49] 308(2.2%) 0(0.0%) compile-top9 (unknown source) compile-forms31 [57] 100.0% ------------------------------------------------------------------- ??? [41] 100.0% [50] 306(2.2%) 306(2.2%) ??? ...cket/collects/racket/private/kw.rkt:1781:39 ------------------------------------------------------------------- ??? [43] 100.0% [51] 3990(28.2%) 0(0.0%) ival-div ...rbie/develop/src/biginterval.rkt:105:0 ??? [70] 64.4% foldl [65] 29.2% bfmax* [63] 6.5% ------------------------------------------------------------------- ??? [45] 100.0% [52] 2484(17.6%) 298(2.1%) iterate-egraph!7 ...lop/src/core/simplify.rkt:61:0 one-iter [59] 88.0% ------------------------------------------------------------------- ??? [43] 100.0% [53] 900(6.4%) 580(4.1%) ival-sub ...erbie/develop/src/biginterval.rkt:82:0 ??? [70] 35.6% ------------------------------------------------------------------- ??? [43] 100.0% [54] 572(4.0%) 0(0.0%) mk-ival ...herbie/develop/src/biginterval.rkt:52:0 bf [61] 100.0% ------------------------------------------------------------------- ->flonum [46] 100.0% [55] 522(3.7%) 522(3.7%) return/no-unsupplied ...ct/private/arr-i.rkt:534:0 ------------------------------------------------------------------- expand-single [48] 100.0% [56] 310(2.2%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [62] 100.0% ------------------------------------------------------------------- compile-top9 [49] 100.0% [57] 308(2.2%) 0(0.0%) compile-forms31 (unknown source) for-loop [74] 100.0% ------------------------------------------------------------------- approximate9 [47] 100.0% [58] 306(2.2%) 0(0.0%) for-loop ...rbie/develop/src/core/taylor.rkt:30:18 taylor [60] 100.0% ------------------------------------------------------------------- iterate-egraph!7 [52] 100.0% [59] 2186(15.5%) 0(0.0%) one-iter ...bie/develop/src/core/simplify.rkt:72:0 for-loop [64] 70.4% for-loop [66] 29.6% ------------------------------------------------------------------- for-loop [58] 15.6% approximate9 [47] 26.5% taylor [60] 57.8% [60] 652(4.6%) 0(0.0%) taylor ...herbie/develop/src/core/taylor.rkt:169:0 taylor [60] 57.8% taylor-invert [68] 26.5% loop [67] 15.6% ------------------------------------------------------------------- mk-ival [54] 100.0% [61] 572(4.0%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [81] 55.2% ??? [82] 44.8% ------------------------------------------------------------------- expand-capturing-lifts [56] 100.0% [62] 310(2.2%) 0(0.0%) lambda-clause-expander (unknown source) finish-bodys [69] 100.0% ------------------------------------------------------------------- ival-div [51] 100.0% [63] 258(1.8%) 0(0.0%) bfmax* .../herbie/develop/src/biginterval.rkt:91:0 foldl [65] 100.0% ------------------------------------------------------------------- for-loop [64] 50.0% one-iter [59] 50.0% [64] 1540(10.9%) 638(4.5%) for-loop ...ie/develop/src/core/simplify.rkt:83:12 for-loop [64] 50.0% match-e [71] 29.3% ------------------------------------------------------------------- bfmax* [63] 18.1% ival-div [51] 81.9% [65] 1422(10.1%) 1164(8.2%) foldl ...et/collects/racket/private/list.rkt:229:4 ??? [70] 18.1% ------------------------------------------------------------------- one-iter [59] 100.0% [66] 646(4.6%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:123:2 for-loop [73] 100.0% ------------------------------------------------------------------- loop [28] 1.2% loop [67] 49.3% taylor [60] 49.5% [67] 618(4.4%) 312(2.2%) loop ...cket/collects/racket/private/map.rkt:40:19 simplify [72] 49.5% loop [67] 49.3% ------------------------------------------------------------------- taylor [60] 100.0% [68] 346(2.4%) 0(0.0%) taylor-invert ...develop/src/core/taylor.rkt:317:0 simplify [72] 100.0% ------------------------------------------------------------------- for-loop [74] 50.0% lambda-clause-expander [62] 50.0% [69] 310(2.2%) 0(0.0%) finish-bodys (unknown source) for-loop [74] 100.0% ------------------------------------------------------------------- foldl [65] 8.2% ival-sub [53] 10.2% ival-div [51] 81.6% [70] 3146(22.2%) 272(1.9%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfdiv [75] 91.4% ------------------------------------------------------------------- for-loop [64] 100.0% [71] 902(6.4%) 600(4.2%) match-e ...herbie/develop/src/core/ematch.rkt:39:0 for-loop [80] 33.5% ------------------------------------------------------------------- loop [67] 46.9% taylor-invert [68] 53.1% [72] 652(4.6%) 0(0.0%) simplify ...erbie/develop/src/core/reduce.rkt:16:0 simplify* [76] 53.1% debug-print [79] 46.9% ------------------------------------------------------------------- for-loop [66] 100.0% [73] 646(4.6%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:106:4 mk-enode! [77] 50.8% merge-egraph-nodes! [78] 49.2% ------------------------------------------------------------------- compile-forms31 [57] 49.8% finish-bodys [69] 50.2% [74] 618(4.4%) 618(4.4%) for-loop (unknown source) finish-bodys [69] 25.1% ------------------------------------------------------------------- ??? [70] 100.0% [75] 2874(20.3%) 0(0.0%) bfdiv ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [81] 79.9% ??? [82] 20.1% ------------------------------------------------------------------- simplify [72] 100.0% [76] 346(2.4%) 0(0.0%) simplify* ...rbie/develop/src/core/reduce.rkt:31:0 hash-ref! [83] 100.0% ------------------------------------------------------------------- for-loop [73] 100.0% [77] 328(2.3%) 0(0.0%) mk-enode! ...bie/develop/src/core/egraph.rkt:102:0 for-loop [84] 100.0% ------------------------------------------------------------------- for-loop [73] 100.0% [78] 318(2.2%) 0(0.0%) merge-egraph-nodes! ...p/src/core/egraph.rkt:153:0 update-leader! [85] 100.0% ------------------------------------------------------------------- simplify [72] 100.0% [79] 306(2.2%) 0(0.0%) debug-print .../herbie/develop/src/debug.rkt:107:0 ??? [86] 100.0% ------------------------------------------------------------------- match-e [71] 100.0% [80] 302(2.1%) 302(2.1%) for-loop ...rbie/develop/src/core/ematch.rkt:52:11 ------------------------------------------------------------------- bf [61] 12.1% bfdiv [75] 87.9% [81] 2612(18.5%) 2612(18.5%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ------------------------------------------------------------------- bf [61] 30.7% bfdiv [75] 69.3% [82] 834(5.9%) 834(5.9%) ??? /home/p92/racket/collects/ffi/unsafe.rkt:898:4 ------------------------------------------------------------------- simplify* [76] 100.0% [83] 346(2.4%) 346(2.4%) hash-ref! .../racket/private/more-scheme.rkt:376:2 ------------------------------------------------------------------- mk-enode! [77] 100.0% [84] 328(2.3%) 328(2.3%) for-loop ...bie/develop/src/core/egraph.rkt:114:10 ------------------------------------------------------------------- merge-egraph-nodes! [78] 100.0% [85] 318(2.2%) 0(0.0%) update-leader! ...evelop/src/core/egraph.rkt:215:0 for-loop [87] 100.0% ------------------------------------------------------------------- debug-print [79] 100.0% [86] 306(2.2%) 0(0.0%) ??? .../contract/private/arrow-val-first.rkt:428:3 keyword-apply [88] 100.0% ------------------------------------------------------------------- update-leader! [85] 100.0% [87] 318(2.2%) 0(0.0%) for-loop ...rbie/develop/src/core/egraph.rkt:230:6 update-en-expr [89] 100.0% ------------------------------------------------------------------- ??? [86] 100.0% [88] 306(2.2%) 306(2.2%) keyword-apply ...lects/racket/private/kw.rkt:421:2 ------------------------------------------------------------------- for-loop [87] 100.0% [89] 318(2.2%) 0(0.0%) update-en-expr ...evelop/src/core/egraph.rkt:209:0 for-loop [90] 100.0% ------------------------------------------------------------------- update-en-expr [89] 100.0% [90] 318(2.2%) 318(2.2%) for-loop ...rbie/develop/src/core/egraph.rkt:211:6 -------------------------------------------------------------------