Profiling results ----------------- Total cpu time observed: 22290ms (out of 22748ms) Number of samples taken: 59 (once every 378ms) ======================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ======================================================================== ??? [7] 100.0% [1] 22290(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [4] 100.0% ------------------------------------------------------------------------ ??? [63] 9.9% ??? [7] 90.1% [2] 3072(13.8%) 0(0.0%) errors ...lies/herbie/develop/src/points.rkt:228:0 for-loop [5] 90.1% eval-prog [6] 9.9% ------------------------------------------------------------------------ loop [52] 100.0% [3] 300(1.3%) 0(0.0%) ??? .../herbie/develop/src/core/localize.rkt:43:32 ??? [50] 100.0% ------------------------------------------------------------------------ profile-thunk16 [1] 100.0% [4] 22290(100.0%) 0(0.0%) run ...et-7.0/share/pkgs/profile-lib/main.rkt:39:2 ??? [7] 100.0% ------------------------------------------------------------------------ errors [2] 100.0% [5] 2768(12.4%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:230:2 ??? [7] 100.0% ------------------------------------------------------------------------ errors [2] 100.0% [6] 304(1.4%) 0(0.0%) eval-prog ...herbie/develop/src/programs.rkt:130:0 temp68_2 [8] 100.0% ------------------------------------------------------------------------ for-loop [5] 4.1% run [4] 47.9% [7] 22290(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 47.9% loop [14] 29.1% oracle-error [9] 6.3% eval-errors [10] 4.9% errors [2] 4.1% run-improve40 [11] 2.8% ??? [50] 1.9% ??? [53] 1.8% errors-score [13] 0.7% ulp-difference [43] 0.5% ------------------------------------------------------------------------ eval-prog [6] 100.0% [8] 304(1.4%) 0(0.0%) temp68_2 (unknown source) compile16 [12] 100.0% ------------------------------------------------------------------------ ??? [7] 100.0% [9] 2814(12.6%) 0(0.0%) oracle-error ...erbie/develop/src/points.rkt:212:0 for-loop [15] 100.0% ------------------------------------------------------------------------ ??? [7] 100.0% [10] 2186(9.8%) 0(0.0%) eval-errors ...herbie/develop/src/points.rkt:203:0 for-loop [16] 100.0% ------------------------------------------------------------------------ ??? [7] 100.0% [11] 1232(5.5%) 0(0.0%) run-improve40 ...ie/develop/src/mainloop.rkt:336:0 for-loop [17] 73.7% loop [14] 26.3% ------------------------------------------------------------------------ temp68_2 [8] 100.0% [12] 304(1.4%) 0(0.0%) compile16 (unknown source) temp74_0 [18] 100.0% ------------------------------------------------------------------------ ??? [7] 100.0% [13] 298(1.3%) 0(0.0%) errors-score ...erbie/develop/src/points.rkt:220:0 loop [19] 100.0% ------------------------------------------------------------------------ run-improve40 [11] 2.4% ??? [7] 97.6% [14] 13316(59.7%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:304:2 loop [25] 71.7% loop [20] 23.4% filter-p&e [22] 2.7% for-loop [24] 2.2% ------------------------------------------------------------------------ oracle-error [9] 100.0% [15] 2814(12.6%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:213:2 loop [40] 89.3% map [47] 10.7% ------------------------------------------------------------------------ eval-errors [10] 100.0% [16] 2186(9.8%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:205:2 ??? [53] 100.0% ------------------------------------------------------------------------ run-improve40 [11] 100.0% [17] 908(4.1%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:351:4 run-iter! [21] 100.0% ------------------------------------------------------------------------ compile16 [12] 100.0% [18] 304(1.4%) 0(0.0%) temp74_0 (unknown source) expand-single [23] 100.0% ------------------------------------------------------------------------ errors-score [13] 100.0% [19] 298(1.3%) 298(1.3%) loop .../racket-7.0/collects/racket/list.rkt:559:2 ------------------------------------------------------------------------ loop [14] 100.0% [20] 3114(14.0%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:265:2 loop [25] 65.9% make-exacts-walkup [26] 34.1% ------------------------------------------------------------------------ for-loop [17] 100.0% [21] 908(4.1%) 0(0.0%) run-iter! ...herbie/develop/src/mainloop.rkt:317:0 localize! [27] 66.5% choose-best-alt! [30] 33.5% ------------------------------------------------------------------------ loop [14] 100.0% [22] 356(1.6%) 0(0.0%) filter-p&e .../herbie/develop/src/points.rkt:278:0 for-loop [28] 100.0% ------------------------------------------------------------------------ temp74_0 [18] 100.0% [23] 304(1.4%) 0(0.0%) expand-single (unknown source) expand-capturing-lifts [29] 100.0% ------------------------------------------------------------------------ loop [14] 100.0% [24] 294(1.3%) 294(1.3%) for-loop ...s/herbie/develop/src/points.rkt:323:31 ------------------------------------------------------------------------ loop [20] 17.7% loop [14] 82.3% [25] 11604(52.1%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:248:4 loop [40] 100.0% ------------------------------------------------------------------------ loop [20] 100.0% [26] 1062(4.8%) 0(0.0%) make-exacts-walkup ...develop/src/points.rkt:245:0 loop [40] 100.0% ------------------------------------------------------------------------ run-iter! [21] 100.0% [27] 604(2.7%) 0(0.0%) localize! ...herbie/develop/src/mainloop.rkt:116:0 localize-error [31] 100.0% ------------------------------------------------------------------------ filter-p&e [22] 100.0% [28] 356(1.6%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:280:2 ordinary-value? [32] 100.0% ------------------------------------------------------------------------ expand-single [23] 100.0% [29] 304(1.4%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [33] 100.0% ------------------------------------------------------------------------ run-iter! [21] 100.0% [30] 304(1.4%) 0(0.0%) choose-best-alt! ...develop/src/mainloop.rkt:107:0 ??? [34] 100.0% ------------------------------------------------------------------------ localize! [27] 100.0% [31] 604(2.7%) 0(0.0%) localize-error ...velop/src/core/localize.rkt:52:0 hash-ref! [35] 100.0% ------------------------------------------------------------------------ for-loop [28] 100.0% [32] 356(1.6%) 356(1.6%) ordinary-value? ...rbie/develop/src/float.rkt:54:0 ------------------------------------------------------------------------ expand-capturing-lifts [29] 100.0% [33] 304(1.4%) 0(0.0%) lambda-clause-expander (unknown source) loop [36] 100.0% ------------------------------------------------------------------------ choose-best-alt! [30] 100.0% [34] 304(1.4%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:388:18 ??? [37] 100.0% ------------------------------------------------------------------------ loop [40] 33.1% localize-error [31] 66.9% [35] 604(2.7%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 parse-loop15 [38] 83.4% f9 [45] 16.6% ------------------------------------------------------------------------ lambda-clause-expander [33] 100.0% [36] 304(1.4%) 0(0.0%) loop (unknown source) dispatch-transformer41 [39] 100.0% ------------------------------------------------------------------------ ??? [34] 100.0% [37] 304(1.4%) 0(0.0%) ??? .../herbie/develop/src/core/alt-table.rkt:59:0 ??? [50] 100.0% ------------------------------------------------------------------------ hash-ref! [35] 100.0% [38] 604(2.7%) 0(0.0%) parse-loop15 ...s/racket/match/compiler.rkt:418:15 loop [40] 100.0% ------------------------------------------------------------------------ loop [36] 100.0% [39] 304(1.4%) 0(0.0%) dispatch-transformer41 (unknown source) apply-transformer52 [41] 100.0% ------------------------------------------------------------------------ make-exacts-walkup [26] 0.0% loop [25] 0.6% parse-loop15 [38] 1.9% for-loop [15] 15.9% loop [40] 81.5% [40] 15782(70.8%) 0(0.0%) loop ...-7.0/collects/racket/private/map.rkt:40:19 loop [40] 81.5% ??? [53] 14.0% ulp-difference [43] 1.9% hash-ref! [35] 1.9% composed [42] 0.4% ??? [50] 0.2% map [47] 0.0% ------------------------------------------------------------------------ dispatch-transformer41 [39] 100.0% [41] 304(1.4%) 0(0.0%) apply-transformer52 (unknown source) apply-transformer-in-context [44]100.0% ------------------------------------------------------------------------ loop [40] 100.0% [42] 6644(29.8%) 0(0.0%) composed ...ollects/racket/private/list.rkt:321:16 ??? [53] 100.0% ------------------------------------------------------------------------ loop [40] 48.4% ??? [7] 51.6% [43] 628(2.8%) 0(0.0%) ulp-difference ...erbie/develop/src/float.rkt:14:0 f9 [45] 100.0% ------------------------------------------------------------------------ apply-transformer52 [41] 100.0% [44] 304(1.4%) 0(0.0%) apply-transformer-in-context (unknown source) ??? [46] 100.0% ------------------------------------------------------------------------ hash-ref! [35] 32.3% ulp-difference [43] 67.7% [45] 928(4.2%) 0(0.0%) f9 ....0/collects/racket/match/compiler.rkt:507:40 ??? [48] 67.7% map [47] 32.3% ------------------------------------------------------------------------ apply-transformer-in-context [44]100.0% [46] 304(1.4%) 0(0.0%) ??? ...collects/racket/private/qq-and-or.rkt:155:9 ??? [49] 100.0% ------------------------------------------------------------------------ f9 [45] 33.1% for-loop [15] 33.3% loop [40] 33.6% [47] 906(4.1%) 0(0.0%) map ...t-7.0/collects/racket/private/map.rkt:35:13 ??? [50] 33.6% ??? [51] 33.3% loop [52] 33.1% ------------------------------------------------------------------------ f9 [45] 100.0% [48] 628(2.8%) 628(2.8%) ??? ...-7.0/collects/racket/lazy-require.rkt:106:6 ------------------------------------------------------------------------ ??? [46] 100.0% [49] 304(1.4%) 304(1.4%) ??? ...ollects/racket/private/qq-and-or.rkt:164:21 ------------------------------------------------------------------------ ??? [3] 4.2% ??? [37] 4.3% map [47] 4.3% ??? [7] 17.6% loop [40] 69.6% [50] 7098(31.8%) 348(1.6%) ??? ...tract/private/arrow-higher-order.rkt:357:33 double->posit16 [59] 49.1% ->flonum [54] 41.7% best-alt [55] 4.3% ------------------------------------------------------------------------ map [47] 100.0% [51] 302(1.4%) 0(0.0%) ??? ...htlies/herbie/develop/src/points.rkt:214:26 ??? [53] 100.0% ------------------------------------------------------------------------ map [47] 1.9% loop [52] 98.1% [52] 300(1.3%) 0(0.0%) loop ...-7.0/collects/racket/private/map.rkt:54:19 loop [52] 98.1% ??? [3] 1.9% ------------------------------------------------------------------------ ??? [51] 2.2% ??? [7] 8.8% for-loop [16] 16.1% loop [40] 24.1% composed [42] 48.8% [53] 13616(61.1%) 0(0.0%) ??? ...tlies/herbie/develop/src/programs.rkt:146:2 ??? [56] 63.5% ->bf [57] 36.5% ------------------------------------------------------------------------ ??? [50] 100.0% [54] 2962(13.3%) 2662(11.9%) ->flonum ...s/herbie/develop/src/programs.rkt:38:0 ??? [71] 10.1% ------------------------------------------------------------------------ ??? [50] 100.0% [55] 304(1.4%) 0(0.0%) best-alt .../herbie/develop/src/mainloop.rkt:102:0 argmins [58] 100.0% ------------------------------------------------------------------------ ??? [53] 100.0% [56] 8640(38.8%) 644(2.9%) ??? ...t-7.0/collects/racket/private/kw.rkt:592:14 double->posit16 [59] 64.7% big-posit16-mul [61] 24.1% big-posit16-add [62] 3.7% ------------------------------------------------------------------------ ??? [53] 100.0% [57] 4976(22.3%) 696(3.1%) ->bf ...tlies/herbie/develop/src/programs.rkt:62:0 bf [60] 86.0% ------------------------------------------------------------------------ best-alt [55] 100.0% [58] 304(1.4%) 0(0.0%) argmins ...lies/herbie/develop/src/common.rkt:93:0 ??? [63] 100.0% ------------------------------------------------------------------------ ??? [50] 38.4% ??? [56] 61.6% [59] 9074(40.7%) 356(1.6%) double->posit16 ...ftposit-rkt/softposit.rkt:248:0 get-ffi-obj* [64] 96.1% ------------------------------------------------------------------------ ->bf [57] 100.0% [60] 4280(19.2%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [65] 91.8% ??? [71] 8.2% ------------------------------------------------------------------------ ??? [56] 100.0% [61] 2086(9.4%) 0(0.0%) big-posit16-mul .../src/syntax/softposit.rkt:110:0 bf* [66] 100.0% ------------------------------------------------------------------------ ??? [56] 100.0% [62] 320(1.4%) 320(1.4%) big-posit16-add .../src/syntax/softposit.rkt:108:0 ------------------------------------------------------------------------ argmins [58] 100.0% [63] 304(1.4%) 0(0.0%) ??? ...lies/herbie/develop/src/mainloop.rkt:104:19 errors [2] 100.0% ------------------------------------------------------------------------ double->posit16 [59] 100.0% [64] 8718(39.1%) 1296(5.8%) get-ffi-obj* ...-7.0/collects/ffi/unsafe.rkt:209:2 get-ffi-lib9 [67] 85.1% ------------------------------------------------------------------------ bf [60] 100.0% [65] 3928(17.6%) 3928(17.6%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ------------------------------------------------------------------------ big-posit16-mul [61] 100.0% [66] 2086(9.4%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [68] 100.0% ------------------------------------------------------------------------ get-ffi-obj* [64] 100.0% [67] 7422(33.3%) 2300(10.3%) get-ffi-lib9 ...-7.0/collects/ffi/unsafe.rkt:121:0 get-cross-lib-search-dirs [69] 69.0% ------------------------------------------------------------------------ bf* [66] 100.0% [68] 2086(9.4%) 0(0.0%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [70] 100.0% ------------------------------------------------------------------------ get-ffi-lib9 [67] 100.0% [69] 5122(23.0%) 3506(15.7%) get-cross-lib-search-dirs ...rivate/dirs.rkt:168:7 ??? [72] 31.6% ------------------------------------------------------------------------ ??? [68] 100.0% [70] 2086(9.4%) 0(0.0%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:821:4 ??? [71] 100.0% ------------------------------------------------------------------------ ->flonum [54] 11.0% bf [60] 12.9% bfmul [70] 76.2% [71] 2738(12.3%) 2738(12.3%) ??? /opt/racket-7.0/collects/ffi/unsafe.rkt:898:4 ------------------------------------------------------------------------ get-cross-lib-search-dirs [69] 100.0% [72] 1616(7.2%) 636(2.9%) ??? ...racket-7.0/collects/racket/promise.rkt:42:2 call-with-exception-handler [73] 60.6% ------------------------------------------------------------------------ ??? [72] 100.0% [73] 980(4.4%) 622(2.8%) call-with-exception-handler ...re-scheme.rkt:265:2 ??? [74] 36.5% ------------------------------------------------------------------------ call-with-exception-handler [73] 100.0% [74] 358(1.6%) 0(0.0%) ??? ...acket-7.0/collects/racket/promise.rkt:74:15 find-lib-dir3 [75] 100.0% ------------------------------------------------------------------------ ??? [74] 100.0% [75] 358(1.6%) 358(1.6%) find-lib-dir3 ...cts/setup/private/dirs.rkt:195:13 ------------------------------------------------------------------------