Profiling results ----------------- Total cpu time observed: 138260ms (out of 141100ms) Number of samples taken: 351 (once every 394ms) ======================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ======================================================================== ??? [10] 100.0% [1] 138260(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [6] 100.0% ------------------------------------------------------------------------ loop [23] 0.5% loop [27] 99.5% [2] 126526(91.5%) 358(0.3%) composed ...ollects/racket/private/list.rkt:321:16 ??? [7] 99.7% ------------------------------------------------------------------------ ??? [10] 100.0% [3] 4924(3.6%) 0(0.0%) run-improve40 ...ie/develop/src/mainloop.rkt:340:0 loop [13] 73.5% for-loop [8] 26.5% ------------------------------------------------------------------------ hash-ref! [33] 100.0% [4] 1300(0.9%) 0(0.0%) ??? ...tlies/herbie/develop/src/programs.rkt:189:5 loop [27] 100.0% ------------------------------------------------------------------------ ??? [10] 100.0% [5] 356(0.3%) 0(0.0%) oracle-error ...erbie/develop/src/points.rkt:212:0 for-loop [9] 100.0% ------------------------------------------------------------------------ profile-thunk16 [1] 100.0% [6] 138260(100.0%) 0(0.0%) run ...et-7.0/share/pkgs/profile-lib/main.rkt:39:2 ??? [10] 100.0% ------------------------------------------------------------------------ for-loop [81] 0.5% loop [27] 2.7% composed [2] 96.8% [7] 130296(94.2%) 780(0.6%) ??? ...tlies/herbie/develop/src/programs.rkt:146:2 ??? [11] 92.4% bf [41] 6.6% ??? [35] 0.2% ??? [63] 0.2% ------------------------------------------------------------------------ run-improve40 [3] 100.0% [8] 1304(0.9%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:355:4 run-iter! [12] 100.0% ------------------------------------------------------------------------ oracle-error [5] 100.0% [9] 356(0.3%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:213:2 loop [27] 100.0% ------------------------------------------------------------------------ run [6] 50.0% [10] 138260(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 50.0% loop [13] 47.8% run-improve40 [3] 1.8% eval-errors [15] 0.2% oracle-error [5] 0.1% errors [80] 0.1% ------------------------------------------------------------------------ ??? [7] 100.0% [11] 120366(87.1%) 0(0.0%) ??? ...t-7.0/collects/racket/private/kw.rkt:592:14 ??? [28] 99.7% ??? [63] 0.3% ------------------------------------------------------------------------ for-loop [8] 100.0% [12] 1304(0.9%) 0(0.0%) run-iter! ...herbie/develop/src/mainloop.rkt:321:0 localize! [14] 77.8% choose-best-alt! [16] 22.2% ------------------------------------------------------------------------ run-improve40 [3] 2.7% ??? [10] 97.3% [13] 135666(98.1%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:303:2 loop [23] 54.0% loop [17] 31.1% make-exacts-walkup [24] 12.1% make-exacts-halfpoints [18] 1.9% for-loop [19] 1.0% ------------------------------------------------------------------------ run-iter! [12] 100.0% [14] 1014(0.7%) 0(0.0%) localize! ...herbie/develop/src/mainloop.rkt:120:0 localize-error [20] 100.0% ------------------------------------------------------------------------ ??? [10] 100.0% [15] 612(0.4%) 0(0.0%) eval-errors ...herbie/develop/src/points.rkt:203:0 for-loop [21] 100.0% ------------------------------------------------------------------------ run-iter! [12] 100.0% [16] 290(0.2%) 0(0.0%) choose-best-alt! ...develop/src/mainloop.rkt:111:0 ??? [22] 100.0% ------------------------------------------------------------------------ loop [13] 100.0% [17] 42152(30.5%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:264:2 loop [23] 59.1% make-exacts-walkup [24] 39.2% eval-prog [29] 1.7% ------------------------------------------------------------------------ loop [13] 100.0% [18] 2536(1.8%) 0(0.0%) make-exacts-halfpoints ...lop/src/points.rkt:262:0 make-exacts-walkup [24] 64.3% loop [23] 35.7% ------------------------------------------------------------------------ loop [13] 100.0% [19] 1298(0.9%) 0(0.0%) for-loop ...s/herbie/develop/src/points.rkt:319:47 ??? [25] 100.0% ------------------------------------------------------------------------ localize! [14] 100.0% [20] 1014(0.7%) 344(0.2%) localize-error ...velop/src/core/localize.rkt:52:0 hash-ref! [33] 66.1% ------------------------------------------------------------------------ eval-errors [15] 100.0% [21] 612(0.4%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:205:2 ulp-difference [44] 100.0% ------------------------------------------------------------------------ choose-best-alt! [16] 100.0% [22] 290(0.2%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:388:18 ??? [26] 100.0% ------------------------------------------------------------------------ make-exacts-halfpoints [18] 0.9% loop [17] 25.1% loop [13] 73.9% [23] 99146(71.7%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:247:4 loop [27] 99.4% composed [2] 0.6% ------------------------------------------------------------------------ make-exacts-halfpoints [18] 4.7% loop [13] 47.4% loop [17] 47.8% [24] 34518(25.0%) 0(0.0%) make-exacts-walkup ...develop/src/points.rkt:244:0 loop [27] 92.4% eval-prog [29] 7.6% ------------------------------------------------------------------------ for-loop [19] 100.0% [25] 1298(0.9%) 292(0.2%) ??? ...ghtlies/herbie/develop/src/points.rkt:129:2 ??? [63] 51.3% ??? [28] 26.2% ------------------------------------------------------------------------ ??? [22] 100.0% [26] 290(0.2%) 0(0.0%) ??? .../herbie/develop/src/core/alt-table.rkt:54:0 ??? [63] 100.0% ------------------------------------------------------------------------ make-exacts-walkup [24] 0.2% parse-loop15 [45] 0.2% for-loop [9] 0.3% ??? [4] 1.0% loop [23] 1.0% loop [27] 97.2% [27] 132730(96.0%) 332(0.2%) loop ...-7.0/collects/racket/private/map.rkt:40:19 loop [27] 97.2% hash-ref! [33] 1.2% composed [2] 1.2% point-error [38] 0.3% ??? [7] 0.0% ??? [63] 0.0% map [54] 0.0% ------------------------------------------------------------------------ ??? [25] 0.3% ??? [11] 99.7% [28] 120328(87.0%) 342(0.2%) ??? ...tract/private/arrow-higher-order.rkt:361:33 bf-complex-exp [30] 71.1% bf-complex-div [31] 23.4% bf-complex-neg [32] 3.2% bf-complex-add [34] 1.7% ??? [35] 0.3% ------------------------------------------------------------------------ loop [17] 21.2% make-exacts-walkup [24] 78.8% [29] 3324(2.4%) 0(0.0%) eval-prog ...herbie/develop/src/programs.rkt:130:0 hash-ref! [33] 59.7% inductor [36] 18.5% temp68_2 [37] 11.1% loop [39] 10.7% ------------------------------------------------------------------------ ??? [28] 100.0% [30] 85582(61.9%) 722(0.5%) bf-complex-exp .../develop/src/bigcomplex.rkt:37:0 ??? [51] 91.3% bf* [40] 7.1% ...higher-order.rkt:361:33 [42] 0.8% ------------------------------------------------------------------------ ??? [28] 100.0% [31] 28166(20.4%) 668(0.5%) bf-complex-div .../develop/src/bigcomplex.rkt:54:0 bf* [40] 45.1% ??? [51] 44.9% ...higher-order.rkt:361:33 [42] 7.6% ------------------------------------------------------------------------ ??? [28] 100.0% [32] 3874(2.8%) 0(0.0%) bf-complex-neg .../develop/src/bigcomplex.rkt:20:0 ??? [51] 92.0% ...higher-order.rkt:361:33 [42] 8.0% ------------------------------------------------------------------------ localize-error [20] 6.9% map [54] 9.2% loop [27] 38.7% eval-prog [29] 45.2% [33] 2654(1.9%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 bigfloat-hash [43] 45.2% ??? [4] 29.6% parse-loop15 [45] 13.7% f9 [46] 11.5% ------------------------------------------------------------------------ ??? [28] 100.0% [34] 2024(1.5%) 0(0.0%) bf-complex-add .../develop/src/bigcomplex.rkt:23:0 ??? [51] 84.2% ...higher-order.rkt:361:33 [42] 15.8% ------------------------------------------------------------------------ ??? [7] 48.6% ??? [28] 51.4% [35] 662(0.5%) 322(0.2%) ??? ...ects/racket/contract/private/prop.rkt:519:4 ??? [50] 51.4% ------------------------------------------------------------------------ inductor [36] 34.4% eval-prog [29] 65.6% [36] 616(0.4%) 298(0.2%) inductor .../herbie/develop/src/programs.rkt:135:4 inductor [36] 34.4% bf [41] 17.2% ------------------------------------------------------------------------ eval-prog [29] 100.0% [37] 368(0.3%) 0(0.0%) temp68_2 (unknown source) compile16 [47] 100.0% ------------------------------------------------------------------------ loop [27] 100.0% [38] 356(0.3%) 0(0.0%) point-error ...herbie/develop/src/points.rkt:195:0 ulp-difference [44] 100.0% ------------------------------------------------------------------------ loop [39] 33.3% expand-capturing-lifts [48] 33.3% eval-prog [29] 33.3% [39] 356(0.3%) 0(0.0%) loop (unknown source) expand-capturing-lifts [48] 33.3% loop [39] 33.3% for-loop [49] 33.3% ------------------------------------------------------------------------ bf-complex-exp [30] 32.3% bf-complex-div [31] 67.7% [40] 18776(13.6%) 360(0.3%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [51] 98.1% ------------------------------------------------------------------------ inductor [36] 3.6% ??? [7] 96.4% [41] 8856(6.4%) 358(0.3%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [68] 79.8% ??? [69] 16.1% ------------------------------------------------------------------------ bf-complex-neg [32] 9.0% bf-complex-add [34] 9.3% bf-complex-exp [30] 19.8% bf-complex-div [31] 62.0% [42] 3450(2.5%) 1088(0.8%) ...higher-order.rkt:361:33 (unknown source) ??? [52] 68.5% ------------------------------------------------------------------------ hash-ref! [33] 100.0% [43] 1984(1.4%) 0(0.0%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:167:0 sig+exp->bigfloat [53] 84.3% bfcanonicalize [60] 15.7% ------------------------------------------------------------------------ point-error [38] 36.8% for-loop [21] 63.2% [44] 968(0.7%) 612(0.4%) ulp-difference ...erbie/develop/src/float.rkt:14:0 return/no-unsupplied [56] 36.8% ------------------------------------------------------------------------ hash-ref! [33] 100.0% [45] 670(0.5%) 0(0.0%) parse-loop15 ...s/racket/match/compiler.rkt:418:15 loop [27] 100.0% ------------------------------------------------------------------------ hash-ref! [33] 100.0% [46] 670(0.5%) 0(0.0%) f9 ....0/collects/racket/match/compiler.rkt:507:40 map [54] 100.0% ------------------------------------------------------------------------ temp68_2 [37] 100.0% [47] 368(0.3%) 0(0.0%) compile16 (unknown source) temp74_0 [55] 100.0% ------------------------------------------------------------------------ loop [39] 100.0% [48] 356(0.3%) 0(0.0%) expand-capturing-lifts (unknown source) loop [39] 100.0% ------------------------------------------------------------------------ run-module-instance!125 [58] 50.0% loop [39] 50.0% [49] 356(0.3%) 0(0.0%) for-loop (unknown source) run-module-instance!125 [58] 50.0% [running body] [57] 50.0% ------------------------------------------------------------------------ ??? [35] 100.0% [50] 340(0.2%) 0(0.0%) ??? ...lects/racket/contract/private/orc.rkt:83:14 ??? [59] 100.0% ------------------------------------------------------------------------ bf-complex-add [34] 1.5% bf-complex-neg [32] 3.1% bf-complex-div [31] 11.1% bf* [40] 16.1% bf-complex-exp [30] 68.2% [51] 114446(82.8%) 69808(50.5%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [61] 21.2% bfneg [62] 17.8% ------------------------------------------------------------------------ ...higher-order.rkt:361:33 [42]100.0% [52] 2362(1.7%) 1668(1.2%) ??? ...cts/racket/contract/private/list.rkt:209:10 ??? [64] 29.4% ------------------------------------------------------------------------ bigfloat-hash [43] 100.0% [53] 1672(1.2%) 0(0.0%) sig+exp->bigfloat ...ivate/bigfloat/mpfr.rkt:387:0 new-mpfr [68] 100.0% ------------------------------------------------------------------------ loop [27] 26.9% f9 [46] 73.1% [54] 670(0.5%) 0(0.0%) map ...t-7.0/collects/racket/private/map.rkt:35:13 hash-ref! [33] 57.7% ??? [63] 26.9% loop [67] 15.4% ------------------------------------------------------------------------ compile16 [47] 100.0% [55] 368(0.3%) 0(0.0%) temp74_0 (unknown source) compile-top9 [65] 100.0% ------------------------------------------------------------------------ ulp-difference [44] 100.0% [56] 356(0.3%) 356(0.3%) return/no-unsupplied ...ct/private/arr-i.rkt:534:0 ------------------------------------------------------------------------ for-loop [49] 100.0% [57] 356(0.3%) 0(0.0%) [running body] ...0/collects/syntax/parse.rkt":##f do-partial-app [66] 100.0% ------------------------------------------------------------------------ for-loop [49] 100.0% [58] 356(0.3%) 0(0.0%) run-module-instance!125 (unknown source) for-loop [49] 100.0% ------------------------------------------------------------------------ ??? [50] 100.0% [59] 340(0.2%) 340(0.2%) ??? ...ects/racket/contract/private/list.rkt:163:5 ------------------------------------------------------------------------ bigfloat-hash [43] 100.0% [60] 312(0.2%) 312(0.2%) bfcanonicalize .../private/bigfloat/mpfr.rkt:157:0 ------------------------------------------------------------------------ ??? [51] 100.0% [61] 24270(17.6%) 392(0.3%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [68] 71.9% ??? [69] 26.5% ------------------------------------------------------------------------ ??? [51] 100.0% [62] 20368(14.7%) 714(0.5%) bfneg ...-lib/math/private/bigfloat/mpfr.rkt:618:4 new-mpfr [68] 72.4% ??? [69] 24.1% ------------------------------------------------------------------------ ??? [7] 6.1% ??? [26] 6.1% map [54] 15.2% ??? [11] 16.0% ??? [25] 28.2% loop [27] 28.3% [63] 2364(1.7%) 678(0.5%) ??? ...tract/private/arrow-higher-order.rkt:357:33 ->flonum [70] 37.0% sample-multi-bounded [71] 28.2% best-alt [75] 6.1% ------------------------------------------------------------------------ ??? [52] 100.0% [64] 694(0.5%) 694(0.5%) ??? ...ects/racket/contract/private/guts.rkt:746:8 ------------------------------------------------------------------------ temp74_0 [55] 100.0% [65] 368(0.3%) 0(0.0%) compile-top9 (unknown source) compile-forms31 [72] 100.0% ------------------------------------------------------------------------ [running body] [57] 100.0% [66] 356(0.3%) 0(0.0%) do-partial-app ...ntract/private/provide.rkt:431:0 ??? [73] 100.0% ------------------------------------------------------------------------ map [54] 3.8% loop [67] 96.2% [67] 310(0.2%) 0(0.0%) loop ...-7.0/collects/racket/private/map.rkt:54:19 loop [67] 96.2% ??? [74] 3.8% ------------------------------------------------------------------------ sig+exp->bigfloat [53] 4.1% bf [41] 17.3% bfneg [62] 36.0% bfmul [61] 42.6% [68] 40934(29.6%) 40934(29.6%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ------------------------------------------------------------------------ bf [41] 11.2% bfneg [62] 38.5% bfmul [61] 50.3% [69] 12768(9.2%) 12768(9.2%) ??? /opt/racket-7.0/collects/ffi/unsafe.rkt:898:4 ------------------------------------------------------------------------ ??? [63] 100.0% [70] 1020(0.7%) 660(0.5%) ->flonum ...s/herbie/develop/src/programs.rkt:38:0 ??? [76] 35.3% ------------------------------------------------------------------------ ??? [63] 100.0% [71] 666(0.5%) 308(0.2%) sample-multi-bounded ...evelop/src/points.rkt:16:0 ??? [76] 53.8% ------------------------------------------------------------------------ compile-top9 [65] 100.0% [72] 368(0.3%) 368(0.3%) compile-forms31 (unknown source) ------------------------------------------------------------------------ ??? [73] 50.0% do-partial-app [66] 50.0% [73] 356(0.3%) 0(0.0%) ??? ...ntract/private/arrow-higher-order.rkt:554:2 ??? [73] 50.0% get-blame-party-info [77] 50.0% ------------------------------------------------------------------------ loop [67] 100.0% [74] 310(0.2%) 310(0.2%) ??? .../herbie/develop/src/core/localize.rkt:43:32 ------------------------------------------------------------------------ ??? [63] 100.0% [75] 290(0.2%) 0(0.0%) best-alt .../herbie/develop/src/mainloop.rkt:106:0 argmins [78] 100.0% ------------------------------------------------------------------------ sample-multi-bounded [71] 49.9% ->flonum [70] 50.1% [76] 718(0.5%) 718(0.5%) ??? ...s/racket/contract/private/arr-i.rkt:1252:29 ------------------------------------------------------------------------ ??? [73] 100.0% [77] 356(0.3%) 356(0.3%) get-blame-party-info ...ate/arrow-common.rkt:136:0 ------------------------------------------------------------------------ best-alt [75] 100.0% [78] 290(0.2%) 0(0.0%) argmins ...lies/herbie/develop/src/common.rkt:93:0 ??? [79] 100.0% ------------------------------------------------------------------------ argmins [78] 100.0% [79] 290(0.2%) 0(0.0%) ??? ...lies/herbie/develop/src/mainloop.rkt:108:19 errors [80] 100.0% ------------------------------------------------------------------------ ??? [79] 47.4% ??? [10] 52.6% [80] 612(0.4%) 0(0.0%) errors ...lies/herbie/develop/src/points.rkt:228:0 for-loop [81] 100.0% ------------------------------------------------------------------------ errors [80] 100.0% [81] 612(0.4%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:230:2 ??? [7] 100.0% ------------------------------------------------------------------------