Profiling results ----------------- Total cpu time observed: 15512ms (out of 15620ms) Number of samples taken: 46 (once every 337ms) ================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ================================================================== ??? [5] 100.0% [1] 15512(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [3] 100.0% ------------------------------------------------------------------ ??? [58] 4.1% ??? [5] 95.9% [2] 3796(24.5%) 0(0.0%) errors ...lies/herbie/develop/src/points.rkt:223:0 for-loop [4] 100.0% ------------------------------------------------------------------ profile-thunk16 [1] 100.0% [3] 15512(100.0%) 0(0.0%) run ...et-7.2/share/pkgs/profile-lib/main.rkt:39:2 ??? [5] 100.0% ------------------------------------------------------------------ errors [2] 100.0% [4] 3796(24.5%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:225:2 ??? [5] 100.0% ------------------------------------------------------------------ for-loop [4] 8.2% run [3] 45.9% [5] 15512(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 45.9% prepare-points [18] 25.8% errors [2] 7.8% ??? [33] 7.5% eval-errors [6] 3.6% oracle-error [7] 2.8% run-improve43 [8] 2.4% ??? [43] 2.0% errors-score [9] 1.6% ulp-difference [10] 0.6% ------------------------------------------------------------------ ??? [5] 100.0% [6] 1112(7.2%) 0(0.0%) eval-errors ...herbie/develop/src/points.rkt:198:0 for-loop [11] 100.0% ------------------------------------------------------------------ ??? [5] 100.0% [7] 864(5.6%) 0(0.0%) oracle-error ...erbie/develop/src/points.rkt:207:0 for-loop [12] 100.0% ------------------------------------------------------------------ ??? [5] 100.0% [8] 790(5.1%) 0(0.0%) run-improve43 ...ie/develop/src/mainloop.rkt:339:0 for-loop [14] 51.1% setup-prog!34 [15] 31.4% simplify! [16] 17.5% ------------------------------------------------------------------ ??? [5] 100.0% [9] 486(3.1%) 0(0.0%) errors-score ...erbie/develop/src/points.rkt:215:0 loop [13] 100.0% ------------------------------------------------------------------ ??? [5] 100.0% [10] 292(1.9%) 198(1.3%) ulp-difference ...erbie/develop/src/float.rkt:46:0 infer-representation [17] 32.2% ------------------------------------------------------------------ eval-errors [6] 100.0% [11] 1112(7.2%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:200:2 ??? [33] 48.6% ??? [42] 28.6% ordinary-value? [29] 22.8% ------------------------------------------------------------------ oracle-error [7] 100.0% [12] 864(5.6%) 288(1.9%) for-loop ...es/herbie/develop/src/points.rkt:208:2 loop [19] 66.7% ------------------------------------------------------------------ errors-score [9] 100.0% [13] 486(3.1%) 0(0.0%) loop .../racket-7.2/collects/racket/list.rkt:559:2 ordinary-value? [29] 100.0% ------------------------------------------------------------------ run-improve43 [8] 100.0% [14] 404(2.6%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:354:4 run-iter! [20] 100.0% ------------------------------------------------------------------ run-improve43 [8] 100.0% [15] 248(1.6%) 0(0.0%) setup-prog!34 ...bie/develop/src/mainloop.rkt:67:0 prepare-points [18] 100.0% ------------------------------------------------------------------ run-improve43 [8] 100.0% [16] 138(0.9%) 0(0.0%) simplify! ...herbie/develop/src/mainloop.rkt:217:0 ??? [21] 100.0% ------------------------------------------------------------------ ulp-difference [10] 100.0% [17] 94(0.6%) 94(0.6%) infer-representation ...develop/src/float.rkt:15:0 ------------------------------------------------------------------ setup-prog!34 [15] 3.0% ??? [5] 97.0% [18] 8258(53.2%) 442(2.8%) prepare-points ...bie/develop/src/points.rkt:166:0 loop [22] 93.2% eval-prog [26] 1.5% ------------------------------------------------------------------ for-loop [12] 100.0% [19] 576(3.7%) 0(0.0%) loop ...-7.2/collects/racket/private/map.rkt:40:19 ??? [42] 53.1% ??? [33] 46.9% ------------------------------------------------------------------ for-loop [14] 100.0% [20] 404(2.6%) 0(0.0%) run-iter! ...herbie/develop/src/mainloop.rkt:320:0 finalize-iter! [23] 61.9% choose-best-alt! [24] 38.1% ------------------------------------------------------------------ simplify! [16] 100.0% [21] 138(0.9%) 0(0.0%) ??? ...s/herbie/develop/src/core/simplify.rkt:26:0 one-iter [25] 100.0% ------------------------------------------------------------------ prepare-points [18] 100.0% [22] 7696(49.6%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:143:4 loop [27] 69.3% ??? [28] 28.6% ordinary-value? [29] 2.1% ------------------------------------------------------------------ run-iter! [20] 100.0% [23] 250(1.6%) 0(0.0%) finalize-iter! ...e/develop/src/mainloop.rkt:271:0 ??? [30] 100.0% ------------------------------------------------------------------ run-iter! [20] 100.0% [24] 154(1.0%) 0(0.0%) choose-best-alt! ...develop/src/mainloop.rkt:111:0 ??? [30] 100.0% ------------------------------------------------------------------ ??? [21] 100.0% [25] 138(0.9%) 0(0.0%) one-iter ...bie/develop/src/core/simplify.rkt:85:0 find-matches [31] 100.0% ------------------------------------------------------------------ prepare-points [18] 100.0% [26] 120(0.8%) 0(0.0%) eval-prog .../herbie/develop/src/programs.rkt:83:0 loop [32] 100.0% ------------------------------------------------------------------ loop [22] 100.0% [27] 5336(34.4%) 250(1.6%) loop ...htlies/herbie/develop/src/points.rkt:102:2 ??? [33] 95.3% ------------------------------------------------------------------ loop [22] 100.0% [28] 2200(14.2%) 0(0.0%) ??? ...ghtlies/herbie/develop/src/points.rkt:127:2 ??? [42] 100.0% ------------------------------------------------------------------ loop [22] 17.8% for-loop [11] 28.2% loop [13] 54.0% [29] 900(5.8%) 0(0.0%) ordinary-value? ...rbie/develop/src/float.rkt:80:0 special-value? [34] 100.0% ------------------------------------------------------------------ choose-best-alt! [24] 38.1% finalize-iter! [23] 61.9% [30] 404(2.6%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:390:18 atab-min-errors [35] 61.9% ??? [36] 38.1% ------------------------------------------------------------------ one-iter [25] 100.0% [31] 138(0.9%) 0(0.0%) find-matches ...develop/src/core/simplify.rkt:55:0 for-loop [37] 100.0% ------------------------------------------------------------------ expand-capturing-lifts [38] 50.0% eval-prog [26] 50.0% [32] 120(0.8%) 120(0.8%) loop (unknown source) expand-capturing-lifts [38] 50.0% ------------------------------------------------------------------ loop [19] 2.9% for-loop [11] 5.7% ??? [5] 37.3% loop [27] 54.1% [33] 9400(60.6%) 1292(8.3%) ??? ...htlies/herbie/develop/src/programs.rkt:99:2 ??? [42] 65.8% ??? [39] 20.5% ------------------------------------------------------------------ ordinary-value? [29] 100.0% [34] 900(5.8%) 0(0.0%) special-value? ...erbie/develop/src/float.rkt:76:0 for-loop [40] 100.0% ------------------------------------------------------------------ ??? [30] 100.0% [35] 250(1.6%) 0(0.0%) atab-min-errors ...op/src/core/alt-table.rkt:221:0 for-loop [41] 100.0% ------------------------------------------------------------------ ??? [30] 100.0% [36] 154(1.0%) 0(0.0%) ??? .../herbie/develop/src/core/alt-table.rkt:59:0 ??? [42] 100.0% ------------------------------------------------------------------ for-loop [37] 50.0% find-matches [31] 50.0% [37] 138(0.9%) 138(0.9%) for-loop ...bie/develop/src/core/simplify.rkt:57:8 for-loop [37] 50.0% ------------------------------------------------------------------ loop [32] 100.0% [38] 120(0.8%) 0(0.0%) expand-capturing-lifts (unknown source) loop [32] 100.0% ------------------------------------------------------------------ ??? [33] 100.0% [39] 1924(12.4%) 0(0.0%) ??? ...t-7.2/collects/racket/private/kw.rkt:594:14 ??? [42] 68.9% ??? [43] 31.1% ------------------------------------------------------------------ special-value? [34] 100.0% [40] 900(5.8%) 542(3.5%) for-loop ...lies/herbie/develop/src/float.rkt:17:3 list-member? [44] 22.0% return/no-unsupplied [59] 17.8% ------------------------------------------------------------------ atab-min-errors [35] 100.0% [41] 250(1.6%) 250(1.6%) for-loop ...e/develop/src/core/alt-table.rkt:222:2 ------------------------------------------------------------------ ??? [36] 0.7% loop [19] 3.0% for-loop [11] 3.1% ??? [39] 12.8% ??? [28] 21.3% ??? [33] 59.1% [42] 10334(66.6%) 2190(14.1%) ??? ...tract/private/arrow-higher-order.rkt:360:33 ->flonum [45] 41.8% mk-ival [46] 16.7% ival-mult [47] 12.8% sample-multi-bounded [48] 6.7% best-alt [50] 0.7% ------------------------------------------------------------------ ??? [39] 49.6% ??? [5] 50.4% [43] 1206(7.8%) 608(3.9%) ??? ...tract/private/arrow-higher-order.rkt:364:33 ival-div [49] 49.6% ------------------------------------------------------------------ for-loop [40] 100.0% [44] 198(1.3%) 198(1.3%) list-member? ...llects/racket/private/set.rkt:24:0 ------------------------------------------------------------------ ??? [42] 100.0% [45] 4398(28.4%) 276(1.8%) ->flonum ...ies/herbie/develop/src/float.rkt:149:0 ??? [51] 93.7% ------------------------------------------------------------------ ??? [42] 100.0% [46] 1726(11.1%) 0(0.0%) mk-ival ...herbie/develop/src/biginterval.rkt:57:0 bf [52] 100.0% ------------------------------------------------------------------ ??? [42] 100.0% [47] 1326(8.5%) 380(2.4%) ival-mult ...bie/develop/src/biginterval.rkt:112:0 bfmul [53] 71.3% ------------------------------------------------------------------ ??? [42] 100.0% [48] 694(4.5%) 694(4.5%) sample-multi-bounded ...evelop/src/points.rkt:15:0 ------------------------------------------------------------------ ??? [43] 100.0% [49] 598(3.9%) 0(0.0%) ival-div ...rbie/develop/src/biginterval.rkt:148:0 bfmul [53] 100.0% ------------------------------------------------------------------ ??? [42] 100.0% [50] 154(1.0%) 0(0.0%) best-alt .../herbie/develop/src/mainloop.rkt:106:0 argmins [54] 100.0% ------------------------------------------------------------------ ->flonum [45] 100.0% [51] 4122(26.6%) 0(0.0%) ??? ...nightlies/herbie/develop/src/float.rkt:23:2 for-loop [55] 100.0% ------------------------------------------------------------------ mk-ival [46] 100.0% [52] 1726(11.1%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 ??? [56] 54.8% new-mpfr [57] 45.2% ------------------------------------------------------------------ ival-div [49] 38.7% ival-mult [47] 61.3% [53] 1544(10.0%) 0(0.0%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:821:4 ??? [56] 58.8% new-mpfr [57] 41.2% ------------------------------------------------------------------ best-alt [50] 100.0% [54] 154(1.0%) 0(0.0%) argmins ...lies/herbie/develop/src/common.rkt:92:0 ??? [58] 100.0% ------------------------------------------------------------------ ??? [51] 100.0% [55] 4122(26.6%) 3804(24.5%) for-loop ...lies/herbie/develop/src/float.rkt:24:5 return/no-unsupplied [59] 7.7% ------------------------------------------------------------------ bfmul [53] 49.0% bf [52] 51.0% [56] 1854(12.0%) 1854(12.0%) ??? /opt/racket-7.2/collects/ffi/unsafe.rkt:905:4 ------------------------------------------------------------------ bfmul [53] 44.9% bf [52] 55.1% [57] 1416(9.1%) 1416(9.1%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ------------------------------------------------------------------ argmins [54] 100.0% [58] 154(1.0%) 0(0.0%) ??? ...lies/herbie/develop/src/mainloop.rkt:108:19 errors [2] 100.0% ------------------------------------------------------------------ for-loop [40] 33.5% for-loop [55] 66.5% [59] 478(3.1%) 318(2.1%) return/no-unsupplied ...ct/private/arr-i.rkt:542:0 filter [60] 33.5% ------------------------------------------------------------------ return/no-unsupplied [59] 100.0% [60] 160(1.0%) 160(1.0%) filter ...2/collects/racket/private/list.rkt:256:2 ------------------------------------------------------------------