Profiling results ----------------- Total cpu time observed: 133820ms (out of 135856ms) Number of samples taken: 360 (once every 372ms) (Hiding functions with self<1.0% and local<2.0%: 1 of 88 hidden) ======================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ======================================================================== ??? [6] 100.0% [1] 133820(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [4] 100.0% ------------------------------------------------------------------------ hash-ref! [34] 100.0% [2] 1002(0.7%) 0(0.0%) parse-loop30 ...s/racket/match/compiler.rkt:418:15 map [18] 100.0% ------------------------------------------------------------------------ ??? [6] 100.0% [3] 540(0.4%) 0(0.0%) errors ...s/herbie/no-costs-2/src/points.rkt:223:0 for-loop [5] 100.0% ------------------------------------------------------------------------ profile-thunk16 [1] 100.0% [4] 133820(100.0%) 0(0.0%) run ...et-7.0/share/pkgs/profile-lib/main.rkt:39:2 ??? [6] 100.0% ------------------------------------------------------------------------ errors [3] 100.0% [5] 540(0.4%) 0(0.0%) for-loop ...herbie/no-costs-2/src/points.rkt:225:2 ??? [6] 100.0% ------------------------------------------------------------------------ for-loop [5] 0.1% run [4] 49.9% [6] 133820(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 49.9% loop [16] 46.7% run-improve43 [7] 2.6% eval-errors [24] 0.2% oracle-error [8] 0.1% errors [3] 0.1% baseline-error [9] 0.1% ulp-difference [35] 0.1% errors-score [10] 0.1% ??? [55] 0.1% ------------------------------------------------------------------------ ??? [6] 100.0% [7] 7016(5.2%) 0(0.0%) run-improve43 ...no-costs-2/src/mainloop.rkt:338:0 setup-prog!34 [11] 81.6% for-loop [12] 18.4% ------------------------------------------------------------------------ ??? [6] 100.0% [8] 396(0.3%) 0(0.0%) oracle-error ...ie/no-costs-2/src/points.rkt:207:0 for-loop [13] 100.0% ------------------------------------------------------------------------ ??? [6] 100.0% [9] 244(0.2%) 0(0.0%) baseline-error .../no-costs-2/src/points.rkt:211:0 argmin [14] 100.0% ------------------------------------------------------------------------ ??? [6] 100.0% [10] 184(0.1%) 0(0.0%) errors-score ...ie/no-costs-2/src/points.rkt:215:0 loop [15] 100.0% ------------------------------------------------------------------------ run-improve43 [7] 100.0% [11] 5724(4.3%) 0(0.0%) setup-prog!34 .../no-costs-2/src/mainloop.rkt:67:0 loop [16] 94.4% ??? [19] 5.6% ------------------------------------------------------------------------ run-improve43 [7] 100.0% [12] 1292(1.0%) 0(0.0%) for-loop ...rbie/no-costs-2/src/mainloop.rkt:353:4 run-iter! [17] 100.0% ------------------------------------------------------------------------ oracle-error [8] 100.0% [13] 396(0.3%) 0(0.0%) for-loop ...herbie/no-costs-2/src/points.rkt:208:2 map [18] 50.0% loop [42] 50.0% ------------------------------------------------------------------------ baseline-error [9] 100.0% [14] 244(0.2%) 0(0.0%) argmin ...acket-7.0/collects/racket/list.rkt:765:0 ??? [20] 100.0% ------------------------------------------------------------------------ errors-score [10] 100.0% [15] 184(0.1%) 184(0.1%) loop .../racket-7.0/collects/racket/list.rkt:559:2 ------------------------------------------------------------------------ setup-prog!34 [11] 4.1% ??? [6] 95.9% [16] 130434(97.5%) 0(0.0%) loop ...ies/herbie/no-costs-2/src/points.rkt:300:2 loop [27] 36.2% loop [21] 35.1% make-exacts-walkup [28] 27.5% for-loop [22] 1.2% ------------------------------------------------------------------------ for-loop [12] 100.0% [17] 1292(1.0%) 0(0.0%) run-iter! ...bie/no-costs-2/src/mainloop.rkt:319:0 localize! [23] 77.6% simplify! [25] 22.4% ------------------------------------------------------------------------ for-loop [13] 16.5% parse-loop30 [2] 83.5% [18] 1200(0.9%) 0(0.0%) map ...t-7.0/collects/racket/private/map.rkt:35:13 hash-ref! [34] 76.1% ??? [26] 16.5% loop [42] 7.4% ------------------------------------------------------------------------ setup-prog!34 [11] 100.0% [19] 322(0.2%) 322(0.2%) ??? ...contract/private/arrow-val-first.rkt:388:18 ------------------------------------------------------------------------ argmin [14] 100.0% [20] 244(0.2%) 0(0.0%) ??? ...ies/herbie/no-costs-2/src/points.rkt:212:27 eval-errors [24] 100.0% ------------------------------------------------------------------------ loop [16] 100.0% [21] 45844(34.3%) 0(0.0%) loop ...ies/herbie/no-costs-2/src/points.rkt:261:2 loop [27] 51.2% make-exacts-walkup [28] 48.8% ------------------------------------------------------------------------ loop [16] 100.0% [22] 1586(1.2%) 0(0.0%) for-loop ...erbie/no-costs-2/src/points.rkt:320:19 ??? [29] 100.0% ------------------------------------------------------------------------ run-iter! [17] 100.0% [23] 1002(0.7%) 0(0.0%) localize! ...bie/no-costs-2/src/mainloop.rkt:119:0 localize-error [30] 100.0% ------------------------------------------------------------------------ ??? [20] 37.4% ??? [6] 62.6% [24] 652(0.5%) 0(0.0%) eval-errors ...bie/no-costs-2/src/points.rkt:198:0 for-loop [31] 100.0% ------------------------------------------------------------------------ run-iter! [17] 100.0% [25] 290(0.2%) 0(0.0%) simplify! ...bie/no-costs-2/src/mainloop.rkt:216:0 ??? [32] 100.0% ------------------------------------------------------------------------ map [18] 100.0% [26] 198(0.1%) 0(0.0%) ??? ...ies/herbie/no-costs-2/src/points.rkt:209:26 ??? [55] 100.0% ------------------------------------------------------------------------ loop [21] 33.2% loop [16] 66.8% [27] 70620(52.8%) 0(0.0%) loop ...ies/herbie/no-costs-2/src/points.rkt:244:4 loop [42] 99.1% composed [45] 0.9% ------------------------------------------------------------------------ loop [21] 38.5% loop [16] 61.5% [28] 58228(43.5%) 0(0.0%) make-exacts-walkup ...costs-2/src/points.rkt:240:0 loop [42] 98.1% eval-prog [33] 1.9% ------------------------------------------------------------------------ for-loop [22] 100.0% [29] 1586(1.2%) 632(0.5%) ??? ...lies/herbie/no-costs-2/src/points.rkt:127:2 ??? [55] 60.2% ------------------------------------------------------------------------ localize! [23] 100.0% [30] 1002(0.7%) 0(0.0%) localize-error ...sts-2/src/core/localize.rkt:57:0 hash-ref! [34] 100.0% ------------------------------------------------------------------------ eval-errors [24] 100.0% [31] 652(0.5%) 198(0.1%) for-loop ...herbie/no-costs-2/src/points.rkt:200:2 ??? [48] 37.4% ulp-difference [35] 32.2% ------------------------------------------------------------------------ simplify! [25] 100.0% [32] 290(0.2%) 0(0.0%) ??? ...erbie/no-costs-2/src/core/simplify.rkt:26:0 for-loop [36] 100.0% ------------------------------------------------------------------------ make-exacts-walkup [28] 100.0% [33] 1120(0.8%) 0(0.0%) eval-prog ...rbie/no-costs-2/src/programs.rkt:83:0 loop [37] 82.3% temp68_2 [41] 17.7% ------------------------------------------------------------------------ localize-error [30] 22.5% map [18] 77.5% [34] 1002(0.7%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 parse-loop30 [2] 86.3% ??? [38] 13.7% ------------------------------------------------------------------------ for-loop [31] 42.9% ??? [6] 57.1% [35] 490(0.4%) 0(0.0%) ulp-difference ...ie/no-costs-2/src/float.rkt:46:0 infer-representation [39] 100.0% ------------------------------------------------------------------------ ??? [32] 100.0% [36] 290(0.2%) 0(0.0%) for-loop ...no-costs-2/src/core/simplify.rkt:31:14 mk-enode-rec! [40] 100.0% ------------------------------------------------------------------------ loop [37] 19.4% expand-capturing-lifts [52] 19.4% eval-prog [33] 61.2% [37] 922(0.7%) 0(0.0%) loop (unknown source) expand-capturing-lifts [52] 61.2% loop [37] 19.4% for-loop [69] 19.4% ------------------------------------------------------------------------ hash-ref! [34] 100.0% [38] 648(0.5%) 0(0.0%) ??? ...rbie/no-costs-2/src/core/localize.rkt:23:13 ??? [55] 54.3% loop [42] 45.7% ------------------------------------------------------------------------ ulp-difference [35] 100.0% [39] 490(0.4%) 0(0.0%) infer-representation ...costs-2/src/float.rkt:15:0 ??? [75] 100.0% ------------------------------------------------------------------------ for-loop [36] 100.0% [40] 290(0.2%) 0(0.0%) mk-enode-rec! ...costs-2/src/core/egraph.rkt:117:0 new-enode [43] 100.0% ------------------------------------------------------------------------ eval-prog [33] 100.0% [41] 198(0.1%) 0(0.0%) temp68_2 (unknown source) compile16 [44] 100.0% ------------------------------------------------------------------------ map [18] 0.0% ??? [38] 0.0% for-loop [13] 0.2% make-exacts-walkup [28] 0.9% loop [27] 1.0% loop [42] 97.9% [42] 127928(95.6%) 0(0.0%) loop ...-7.0/collects/racket/private/map.rkt:40:19 loop [42] 97.9% composed [45] 1.8% ??? [55] 0.2% ??? [48] 0.1% ??? [54] 0.0% ------------------------------------------------------------------------ mk-enode-rec! [40] 100.0% [43] 290(0.2%) 0(0.0%) new-enode ...ie/no-costs-2/src/core/enode.rkt:94:0 type-of-enode-expr [46] 100.0% ------------------------------------------------------------------------ temp68_2 [41] 100.0% [44] 198(0.1%) 0(0.0%) compile16 (unknown source) temp74_0 [47] 100.0% ------------------------------------------------------------------------ loop [27] 0.5% loop [42] 99.5% [45] 119490(89.3%) 0(0.0%) composed ...ollects/racket/private/list.rkt:321:16 ??? [48] 100.0% ------------------------------------------------------------------------ new-enode [43] 100.0% [46] 290(0.2%) 0(0.0%) type-of-enode-expr ...ts-2/src/core/enode.rkt:66:0 constant? [49] 100.0% ------------------------------------------------------------------------ compile16 [44] 100.0% [47] 198(0.1%) 0(0.0%) temp74_0 (unknown source) expand-single [50] 100.0% ------------------------------------------------------------------------ for-loop [31] 0.2% loop [42] 6.4% composed [45] 93.4% [48] 127972(95.6%) 496(0.4%) ??? ...ies/herbie/no-costs-2/src/programs.rkt:99:2 ??? [51] 86.5% ??? [55] 12.7% ??? [83] 0.3% ------------------------------------------------------------------------ type-of-enode-expr [46] 100.0% [49] 290(0.2%) 0(0.0%) constant? ...o-costs-2/src/syntax/syntax.rkt:719:0 value? [53] 100.0% ------------------------------------------------------------------------ temp74_0 [47] 100.0% [50] 198(0.1%) 0(0.0%) expand-single (unknown source) expand-capturing-lifts [52] 100.0% ------------------------------------------------------------------------ ??? [48] 100.0% [51] 110744(82.8%) 614(0.5%) ??? ...t-7.0/collects/racket/private/kw.rkt:592:14 ??? [54] 98.9% ??? [55] 0.6% ------------------------------------------------------------------------ expand-single [50] 17.7% loop [37] 82.3% [52] 1120(0.8%) 0(0.0%) expand-capturing-lifts (unknown source) loop [37] 47.9% dispatch-transformer41 [56] 34.5% lambda-clause-expander [57] 17.7% ------------------------------------------------------------------------ constant? [49] 100.0% [53] 290(0.2%) 290(0.2%) value? ...bie/no-costs-2/src/syntax/types.rkt:18:0 ------------------------------------------------------------------------ loop [42] 0.3% ??? [51] 99.7% [54] 109850(82.1%) 340(0.3%) ??? ...tract/private/arrow-higher-order.rkt:361:33 bf-complex-exp [58] 71.6% bf-complex-div [59] 24.0% bf-complex-neg [62] 2.9% ------------------------------------------------------------------------ ??? [26] 1.0% ??? [6] 1.4% ??? [38] 1.8% loop [42] 2.6% ??? [51] 3.3% ??? [29] 5.0% ??? [48] 84.9% [55] 19206(14.4%) 1734(1.3%) ??? ...tract/private/arrow-higher-order.rkt:357:33 bf [60] 57.6% ->bf [61] 23.1% sample-multi-bounded [63] 5.0% ->flonum [64] 3.4% ??? [83] 1.9% ------------------------------------------------------------------------ expand-capturing-lifts [52] 100.0% [56] 386(0.3%) 0(0.0%) dispatch-transformer41 (unknown source) apply-transformer52 [65] 100.0% ------------------------------------------------------------------------ expand-capturing-lifts [52] 100.0% [57] 198(0.1%) 0(0.0%) lambda-clause-expander (unknown source) finish-bodys [66] 100.0% ------------------------------------------------------------------------ ??? [54] 100.0% [58] 78678(58.8%) 0(0.0%) bf-complex-exp ...-costs-2/src/bigcomplex.rkt:37:0 ??? [73] 93.2% bf* [67] 6.8% ------------------------------------------------------------------------ ??? [54] 100.0% [59] 26384(19.7%) 1334(1.0%) bf-complex-div ...-costs-2/src/bigcomplex.rkt:54:0 ??? [73] 55.9% bf* [67] 36.4% ...higher-order.rkt:361:33 [70] 2.7% ------------------------------------------------------------------------ ??? [55] 100.0% [60] 11072(8.3%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [85] 91.0% ??? [86] 9.0% ------------------------------------------------------------------------ ??? [55] 100.0% [61] 4434(3.3%) 0(0.0%) ->bf ...lies/herbie/no-costs-2/src/float.rkt:170:0 for-loop [68] 100.0% ------------------------------------------------------------------------ ??? [54] 100.0% [62] 3132(2.3%) 0(0.0%) bf-complex-neg ...-costs-2/src/bigcomplex.rkt:20:0 ??? [73] 100.0% ------------------------------------------------------------------------ ??? [55] 100.0% [63] 954(0.7%) 954(0.7%) sample-multi-bounded ...osts-2/src/points.rkt:15:0 ------------------------------------------------------------------------ ??? [55] 100.0% [64] 656(0.5%) 0(0.0%) ->flonum .../herbie/no-costs-2/src/float.rkt:149:0 ??? [71] 100.0% ------------------------------------------------------------------------ dispatch-transformer41 [56] 100.0% [65] 386(0.3%) 0(0.0%) apply-transformer52 (unknown source) syntax-add-shifts44 [72] 100.0% ------------------------------------------------------------------------ lambda-clause-expander [57] 33.3% for-loop [69] 66.7% [66] 198(0.1%) 0(0.0%) finish-bodys (unknown source) for-loop [69] 100.0% ------------------------------------------------------------------------ bf-complex-exp [58] 35.7% bf-complex-div [59] 64.3% [67] 14934(11.2%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [73] 100.0% ------------------------------------------------------------------------ ->bf [61] 100.0% [68] 4434(3.3%) 1132(0.8%) for-loop ...s/herbie/no-costs-2/src/float.rkt:17:3 list-member? [74] 42.2% ??? [75] 16.5% return/no-unsupplied [76] 15.8% ------------------------------------------------------------------------ finish-bodys [66] 27.0% run-module-instance!125 [78] 36.5% loop [37] 36.5% [69] 734(0.5%) 198(0.1%) for-loop (unknown source) run-module-instance!125 [78] 36.5% namespace->name [77] 36.5% finish-bodys [66] 18.0% ------------------------------------------------------------------------ bf-complex-div [59] 100.0% [70] 708(0.5%) 360(0.3%) ...higher-order.rkt:361:33 (unknown source) ??? [80] 49.2% ------------------------------------------------------------------------ ->flonum [64] 100.0% [71] 656(0.5%) 260(0.2%) ??? ...htlies/herbie/no-costs-2/src/float.rkt:23:2 for-loop [79] 60.4% ------------------------------------------------------------------------ apply-transformer52 [65] 100.0% [72] 386(0.3%) 386(0.3%) syntax-add-shifts44 (unknown source) ------------------------------------------------------------------------ bf-complex-neg [62] 2.9% bf-complex-div [59] 13.7% bf* [67] 13.9% bf-complex-exp [58] 68.3% [73] 107468(80.3%) 68870(51.5%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [81] 22.2% bfneg [82] 13.7% ------------------------------------------------------------------------ for-loop [68] 100.0% [74] 1870(1.4%) 1870(1.4%) list-member? ...llects/racket/private/set.rkt:24:0 ------------------------------------------------------------------------ infer-representation [39] 40.1% for-loop [68] 59.9% [75] 1222(0.9%) 1222(0.9%) ??? ...s/racket/contract/private/arr-i.rkt:1252:29 ------------------------------------------------------------------------ for-loop [68] 100.0% [76] 700(0.5%) 0(0.0%) return/no-unsupplied ...ct/private/arr-i.rkt:534:0 filter [84] 100.0% ------------------------------------------------------------------------ for-loop [69] 100.0% [77] 536(0.4%) 536(0.4%) namespace->name (unknown source) ------------------------------------------------------------------------ for-loop [69] 100.0% [78] 536(0.4%) 0(0.0%) run-module-instance!125 (unknown source) for-loop [69] 100.0% ------------------------------------------------------------------------ ??? [71] 100.0% [79] 396(0.3%) 396(0.3%) for-loop ...s/herbie/no-costs-2/src/float.rkt:24:5 ------------------------------------------------------------------------ ...higher-order.rkt:361:33 [70]100.0% [80] 348(0.3%) 0(0.0%) ??? ...cts/racket/contract/private/list.rkt:209:10 ??? [83] 100.0% ------------------------------------------------------------------------ ??? [73] 100.0% [81] 23890(17.9%) 594(0.4%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [85] 75.1% ??? [86] 22.5% ------------------------------------------------------------------------ ??? [73] 100.0% [82] 14708(11.0%) 664(0.5%) bfneg ...-lib/math/private/bigfloat/mpfr.rkt:618:4 new-mpfr [85] 64.1% ??? [86] 31.4% ------------------------------------------------------------------------ ??? [80] 31.0% ??? [55] 31.7% ??? [48] 37.3% [83] 1122(0.8%) 704(0.5%) ??? ...ects/racket/contract/private/guts.rkt:746:8 bigvalue? [87] 37.3% ------------------------------------------------------------------------ return/no-unsupplied [76] 100.0% [84] 700(0.5%) 700(0.5%) filter ...0/collects/racket/private/list.rkt:256:2 ------------------------------------------------------------------------ bfneg [82] 25.2% bf [60] 26.9% bfmul [81] 47.9% [85] 37444(28.0%) 37444(28.0%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ------------------------------------------------------------------------ bf [60] 9.0% bfneg [82] 42.0% bfmul [81] 48.9% [86] 10968(8.2%) 10968(8.2%) ??? /opt/racket-7.0/collects/ffi/unsafe.rkt:898:4 ------------------------------------------------------------------------ ??? [83] 100.0% [87] 418(0.3%) 418(0.3%) bigvalue? .../no-costs-2/src/syntax/types.rkt:19:0 ------------------------------------------------------------------------