Profiling results ----------------- Total cpu time observed: 93386ms (out of 98056ms) Number of samples taken: 220 (once every 424ms) (Hiding functions with self<1.0% and local<2.0%: 1 of 79 hidden) ======================================================================= Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ======================================================================= ??? [12] 100.0% [1] 93386(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [7] 100.0% ----------------------------------------------------------------------- loop [32] 0.8% loop [43] 99.2% [2] 82180(88.0%) 0(0.0%) composed ...ollects/racket/private/list.rkt:321:16 ??? [8] 100.0% ----------------------------------------------------------------------- ??? [12] 100.0% [3] 4018(4.3%) 0(0.0%) run-improve40 ...ie/develop/src/mainloop.rkt:340:0 loop [18] 70.9% for-loop [9] 29.1% ----------------------------------------------------------------------- hash-ref! [35] 100.0% [4] 418(0.4%) 0(0.0%) f9 ....0/collects/racket/match/compiler.rkt:507:40 map [16] 100.0% ----------------------------------------------------------------------- ??? [47] 100.0% [5] 404(0.4%) 0(0.0%) best-alt .../herbie/develop/src/mainloop.rkt:106:0 argmins [10] 100.0% ----------------------------------------------------------------------- ??? [12] 100.0% [6] 328(0.4%) 0(0.0%) oracle-error ...erbie/develop/src/points.rkt:212:0 for-loop [11] 100.0% ----------------------------------------------------------------------- profile-thunk16 [1] 100.0% [7] 93386(100.0%) 0(0.0%) run ...et-7.0/share/pkgs/profile-lib/main.rkt:39:2 ??? [12] 100.0% ----------------------------------------------------------------------- ??? [24] 0.4% for-loop [28] 0.8% loop [43] 4.3% composed [2] 94.5% [8] 86968(93.1%) 1180(1.3%) ??? ...tlies/herbie/develop/src/programs.rkt:146:2 ??? [13] 80.8% bf [14] 17.0% ??? [47] 0.8% ----------------------------------------------------------------------- run-improve40 [3] 100.0% [9] 1170(1.3%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:355:4 run-iter! [15] 100.0% ----------------------------------------------------------------------- best-alt [5] 100.0% [10] 404(0.4%) 0(0.0%) argmins ...lies/herbie/develop/src/common.rkt:93:0 ??? [17] 100.0% ----------------------------------------------------------------------- oracle-error [6] 100.0% [11] 328(0.4%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:213:2 map [16] 100.0% ----------------------------------------------------------------------- run [7] 50.0% [12] 93386(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 50.0% loop [18] 47.0% run-improve40 [3] 2.2% errors [19] 0.4% eval-errors [22] 0.2% oracle-error [6] 0.2% ----------------------------------------------------------------------- ??? [8] 100.0% [13] 70306(75.3%) 0(0.0%) ??? ...t-7.0/collects/racket/private/kw.rkt:592:14 ??? [46] 99.5% ??? [47] 0.5% ----------------------------------------------------------------------- ??? [8] 100.0% [14] 14764(15.8%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [71] 81.7% ??? [72] 18.3% ----------------------------------------------------------------------- for-loop [9] 100.0% [15] 1170(1.3%) 0(0.0%) run-iter! ...herbie/develop/src/mainloop.rkt:321:0 localize! [20] 35.7% choose-best-alt! [21] 34.5% finalize-iter! [23] 29.7% ----------------------------------------------------------------------- for-loop [11] 44.0% f9 [4] 56.0% [16] 746(0.8%) 0(0.0%) map ...t-7.0/collects/racket/private/map.rkt:35:13 hash-ref! [35] 56.0% ??? [24] 44.0% ----------------------------------------------------------------------- argmins [10] 100.0% [17] 404(0.4%) 0(0.0%) ??? ...lies/herbie/develop/src/mainloop.rkt:108:19 errors [19] 100.0% ----------------------------------------------------------------------- run-improve40 [3] 3.1% ??? [12] 96.9% [18] 90576(97.0%) 348(0.4%) loop ...htlies/herbie/develop/src/points.rkt:303:2 loop [32] 59.0% loop [25] 25.7% make-exacts-walkup [33] 10.6% make-exacts-halfpoints [26] 2.6% for-loop [27] 1.6% ----------------------------------------------------------------------- ??? [17] 38.2% ??? [12] 61.8% [19] 1058(1.1%) 0(0.0%) errors ...lies/herbie/develop/src/points.rkt:228:0 for-loop [28] 100.0% ----------------------------------------------------------------------- run-iter! [15] 100.0% [20] 418(0.4%) 0(0.0%) localize! ...herbie/develop/src/mainloop.rkt:120:0 localize-error [30] 100.0% ----------------------------------------------------------------------- run-iter! [15] 100.0% [21] 404(0.4%) 0(0.0%) choose-best-alt! ...develop/src/mainloop.rkt:111:0 ??? [29] 100.0% ----------------------------------------------------------------------- ??? [12] 100.0% [22] 350(0.4%) 0(0.0%) eval-errors ...herbie/develop/src/points.rkt:203:0 for-loop [31] 100.0% ----------------------------------------------------------------------- run-iter! [15] 100.0% [23] 348(0.4%) 0(0.0%) finalize-iter! ...e/develop/src/mainloop.rkt:272:0 ??? [29] 100.0% ----------------------------------------------------------------------- map [16] 100.0% [24] 328(0.4%) 0(0.0%) ??? ...htlies/herbie/develop/src/points.rkt:214:26 ??? [8] 100.0% ----------------------------------------------------------------------- loop [18] 100.0% [25] 23314(25.0%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:264:2 loop [32] 70.4% make-exacts-walkup [33] 29.6% ----------------------------------------------------------------------- loop [18] 100.0% [26] 2362(2.5%) 0(0.0%) make-exacts-halfpoints ...lop/src/points.rkt:262:0 make-exacts-walkup [33] 71.6% loop [32] 28.4% ----------------------------------------------------------------------- loop [18] 100.0% [27] 1492(1.6%) 0(0.0%) for-loop ...s/herbie/develop/src/points.rkt:319:47 ??? [34] 100.0% ----------------------------------------------------------------------- errors [19] 100.0% [28] 1058(1.1%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:230:2 ??? [8] 67.9% ??? [47] 32.1% ----------------------------------------------------------------------- finalize-iter! [23] 46.3% choose-best-alt! [21] 53.7% [29] 752(0.8%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:388:18 ??? [36] 53.7% for-loop [38] 46.3% ----------------------------------------------------------------------- localize! [20] 100.0% [30] 418(0.4%) 0(0.0%) localize-error ...velop/src/core/localize.rkt:52:0 hash-ref! [35] 100.0% ----------------------------------------------------------------------- eval-errors [22] 100.0% [31] 350(0.4%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:205:2 point-error [37] 100.0% ----------------------------------------------------------------------- make-exacts-halfpoints [26] 0.9% loop [25] 23.3% loop [18] 75.8% [32] 70532(75.5%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:247:4 loop [43] 99.0% composed [2] 1.0% ----------------------------------------------------------------------- make-exacts-halfpoints [26] 9.3% loop [25] 37.9% loop [18] 52.8% [33] 18204(19.5%) 0(0.0%) make-exacts-walkup ...develop/src/points.rkt:244:0 loop [43] 96.2% eval-prog [39] 3.8% ----------------------------------------------------------------------- for-loop [27] 100.0% [34] 1492(1.6%) 0(0.0%) ??? ...ghtlies/herbie/develop/src/points.rkt:129:2 ??? [47] 100.0% ----------------------------------------------------------------------- map [16] 50.0% localize-error [30] 50.0% [35] 418(0.4%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 f9 [4] 50.0% parse-loop15 [40] 50.0% ----------------------------------------------------------------------- ??? [29] 100.0% [36] 404(0.4%) 0(0.0%) ??? .../herbie/develop/src/core/alt-table.rkt:59:0 ??? [47] 100.0% ----------------------------------------------------------------------- for-loop [31] 100.0% [37] 350(0.4%) 0(0.0%) point-error ...herbie/develop/src/points.rkt:195:0 ulp-difference [41] 100.0% ----------------------------------------------------------------------- ??? [29] 100.0% [38] 348(0.4%) 0(0.0%) for-loop ...ie/develop/src/core/alt-table.rkt:56:2 atab-add-altn [42] 100.0% ----------------------------------------------------------------------- make-exacts-walkup [33] 100.0% [39] 684(0.7%) 0(0.0%) eval-prog ...herbie/develop/src/programs.rkt:130:0 loop [69] 52.6% temp68_2 [45] 47.4% ----------------------------------------------------------------------- hash-ref! [35] 100.0% [40] 418(0.4%) 0(0.0%) parse-loop15 ...s/racket/match/compiler.rkt:418:15 loop [43] 100.0% ----------------------------------------------------------------------- point-error [37] 100.0% [41] 350(0.4%) 350(0.4%) ulp-difference ...erbie/develop/src/float.rkt:14:0 ----------------------------------------------------------------------- for-loop [38] 100.0% [42] 348(0.4%) 0(0.0%) atab-add-altn ...elop/src/core/alt-table.rkt:202:0 override-at-pnts [44] 100.0% ----------------------------------------------------------------------- parse-loop15 [40] 0.0% make-exacts-walkup [33] 1.1% loop [32] 1.7% loop [43] 97.3% [43] 88098(94.3%) 308(0.3%) loop ...-7.0/collects/racket/private/map.rkt:40:19 loop [43] 97.3% composed [2] 2.5% ??? [8] 0.2% ??? [47] 0.0% ??? [46] 0.0% ----------------------------------------------------------------------- atab-add-altn [42] 100.0% [44] 348(0.4%) 348(0.4%) override-at-pnts ...p/src/core/alt-table.rkt:138:0 ----------------------------------------------------------------------- eval-prog [39] 100.0% [45] 324(0.3%) 0(0.0%) temp68_2 (unknown source) compile16 [48] 100.0% ----------------------------------------------------------------------- loop [43] 0.6% ??? [13] 99.4% [46] 70364(75.3%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:361:33 bf-complex-exp [49] 50.6% bf-complex-div [50] 38.7% bf-complex-neg [51] 6.3% bf-complex-add [52] 4.4% ----------------------------------------------------------------------- ??? [36] 4.0% for-loop [28] 6.7% ??? [13] 7.1% ??? [8] 10.2% ??? [34] 29.6% loop [43] 42.3% [47] 5040(5.4%) 1066(1.1%) ??? ...tract/private/arrow-higher-order.rkt:357:33 ->flonum [53] 45.2% sample-multi-bounded [54] 29.6% best-alt [5] 4.0% ----------------------------------------------------------------------- temp68_2 [45] 100.0% [48] 324(0.3%) 0(0.0%) compile16 (unknown source) temp74_0 [55] 100.0% ----------------------------------------------------------------------- ??? [46] 100.0% [49] 35606(38.1%) 1358(1.5%) bf-complex-exp .../develop/src/bigcomplex.rkt:37:0 ??? [62] 84.0% bf* [56] 11.2% ...higher-order.rkt:361:33 [57] 1.0% ----------------------------------------------------------------------- ??? [46] 100.0% [50] 27204(29.1%) 3010(3.2%) bf-complex-div .../develop/src/bigcomplex.rkt:54:0 ??? [62] 62.7% bf* [56] 26.3% ----------------------------------------------------------------------- ??? [46] 100.0% [51] 4424(4.7%) 322(0.3%) bf-complex-neg .../develop/src/bigcomplex.rkt:20:0 ??? [62] 85.2% ...higher-order.rkt:361:33 [57] 7.5% ----------------------------------------------------------------------- ??? [46] 100.0% [52] 3130(3.4%) 360(0.4%) bf-complex-add .../develop/src/bigcomplex.rkt:23:0 ??? [62] 88.5% ----------------------------------------------------------------------- ??? [47] 100.0% [53] 2482(2.7%) 1032(1.1%) ->flonum ...s/herbie/develop/src/programs.rkt:38:0 ??? [72] 29.2% return/no-unsupplied [58] 16.6% ??? [61] 12.7% ----------------------------------------------------------------------- ??? [47] 100.0% [54] 1492(1.6%) 1156(1.2%) sample-multi-bounded ...evelop/src/points.rkt:16:0 loop [59] 22.5% ----------------------------------------------------------------------- compile16 [48] 100.0% [55] 324(0.3%) 0(0.0%) temp74_0 (unknown source) expand-single [60] 100.0% ----------------------------------------------------------------------- bf-complex-exp [49] 35.8% bf-complex-div [50] 64.2% [56] 11134(11.9%) 362(0.4%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [62] 96.7% ----------------------------------------------------------------------- bf-complex-neg [51] 48.4% bf-complex-exp [49] 51.6% [57] 690(0.7%) 0(0.0%) ...higher-order.rkt:361:33 (unknown source) ??? [64] 51.6% ??? [68] 48.4% ----------------------------------------------------------------------- ->flonum [53] 100.0% [58] 412(0.4%) 412(0.4%) return/no-unsupplied ...ct/private/arr-i.rkt:534:0 ----------------------------------------------------------------------- sample-multi-bounded [54] 100.0% [59] 336(0.4%) 0(0.0%) loop ...ghtlies/herbie/develop/src/points.rkt:45:6 random-exp [65] 100.0% ----------------------------------------------------------------------- temp74_0 [55] 100.0% [60] 324(0.3%) 0(0.0%) expand-single (unknown source) expand-capturing-lifts [63] 100.0% ----------------------------------------------------------------------- ->flonum [53] 100.0% [61] 314(0.3%) 314(0.3%) ??? ...s/racket/contract/private/arr-i.rkt:1252:29 ----------------------------------------------------------------------- bf-complex-add [52] 4.3% bf-complex-neg [51] 5.9% bf* [56] 16.8% bf-complex-div [50] 26.5% bf-complex-exp [49] 46.5% [62] 64262(68.8%) 29260(31.3%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [66] 34.2% bfcos [67] 20.3% ----------------------------------------------------------------------- expand-single [60] 47.4% loop [69] 52.6% [63] 684(0.7%) 0(0.0%) expand-capturing-lifts (unknown source) loop [69] 52.6% lambda-clause-expander [70] 47.4% ----------------------------------------------------------------------- ...higher-order.rkt:361:33 [57]100.0% [64] 356(0.4%) 0(0.0%) ??? ...cts/racket/contract/private/list.rkt:209:10 ??? [68] 100.0% ----------------------------------------------------------------------- loop [59] 100.0% [65] 336(0.4%) 336(0.4%) random-exp .../herbie/develop/src/common.rkt:173:0 ----------------------------------------------------------------------- ??? [62] 100.0% [66] 21954(23.5%) 0(0.0%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [71] 69.4% ??? [72] 30.6% ----------------------------------------------------------------------- ??? [62] 100.0% [67] 13048(14.0%) 334(0.4%) bfcos ...-lib/math/private/bigfloat/mpfr.rkt:618:4 new-mpfr [71] 62.2% ??? [72] 35.2% ----------------------------------------------------------------------- ...higher-order.rkt:361:33 [57] 48.4% ??? [64] 51.6% [68] 690(0.7%) 690(0.7%) ??? ...ects/racket/contract/private/guts.rkt:746:8 ----------------------------------------------------------------------- loop [69] 33.3% expand-capturing-lifts [63] 33.3% eval-prog [39] 33.3% [69] 360(0.4%) 0(0.0%) loop (unknown source) expand-capturing-lifts [63] 33.3% loop [69] 33.3% for-loop [74] 33.3% ----------------------------------------------------------------------- expand-capturing-lifts [63] 100.0% [70] 324(0.3%) 0(0.0%) lambda-clause-expander (unknown source) finish-bodys [73] 100.0% ----------------------------------------------------------------------- bfcos [67] 22.9% bf [14] 34.1% bfmul [66] 43.0% [71] 35412(37.9%) 35412(37.9%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ----------------------------------------------------------------------- ->flonum [53] 4.9% bf [14] 18.3% bfcos [67] 31.2% bfmul [66] 45.6% [72] 14744(15.8%) 14744(15.8%) ??? /opt/racket-7.0/collects/ffi/unsafe.rkt:898:4 ----------------------------------------------------------------------- lambda-clause-expander [70] 8.3% for-loop [74] 91.7% [73] 324(0.3%) 0(0.0%) finish-bodys (unknown source) for-loop [74] 100.0% ----------------------------------------------------------------------- for-loop [74] 3.6% run-module-instance!125 [76] 26.3% loop [69] 26.3% finish-bodys [73] 43.7% [74] 684(0.7%) 0(0.0%) for-loop (unknown source) finish-bodys [73] 40.1% run-module-instance!125 [76] 26.3% [running body] [75] 26.3% for-loop [74] 3.6% expand-implicit [77] 3.6% ----------------------------------------------------------------------- for-loop [74] 100.0% [75] 360(0.4%) 360(0.4%) [running body] .../private/class-internal.rkt":##f ----------------------------------------------------------------------- for-loop [74] 100.0% [76] 360(0.4%) 0(0.0%) run-module-instance!125 (unknown source) for-loop [74] 100.0% ----------------------------------------------------------------------- for-loop [74] 100.0% [77] 324(0.3%) 0(0.0%) expand-implicit (unknown source) binding-lookup50 [78] 100.0% ----------------------------------------------------------------------- expand-implicit [77] 100.0% [78] 324(0.3%) 324(0.3%) binding-lookup50 (unknown source) -----------------------------------------------------------------------