Profiling results ----------------- Total cpu time observed: 21816ms (out of 22380ms) Number of samples taken: 54 (once every 404ms) ================================================================ Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ================================================================ ??? [18] 100.0% [1] 21816(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [4] 100.0% ---------------------------------------------------------------- ??? [18] 100.0% [2] 1762(8.1%) 0(0.0%) errors ...lies/herbie/develop/src/points.rkt:223:0 for-loop [5] 100.0% ---------------------------------------------------------------- ??? [18] 100.0% [3] 1032(4.7%) 0(0.0%) run-improve43 ...ie/develop/src/mainloop.rkt:339:0 simplify! [6] 100.0% ---------------------------------------------------------------- profile-thunk16 [1] 100.0% [4] 21816(100.0%) 0(0.0%) run ...et-7.2/share/pkgs/profile-lib/main.rkt:39:2 ??? [18] 100.0% ---------------------------------------------------------------- errors [2] 100.0% [5] 1762(8.1%) 256(1.2%) for-loop ...es/herbie/develop/src/points.rkt:225:2 ??? [18] 85.5% ---------------------------------------------------------------- run-improve43 [3] 100.0% [6] 1032(4.7%) 0(0.0%) simplify! ...herbie/develop/src/mainloop.rkt:217:0 ??? [7] 100.0% ---------------------------------------------------------------- simplify! [6] 100.0% [7] 1032(4.7%) 0(0.0%) ??? ...s/herbie/develop/src/core/simplify.rkt:26:0 for-loop [8] 63.6% one-iter [9] 36.4% ---------------------------------------------------------------- ??? [7] 100.0% [8] 656(3.0%) 0(0.0%) for-loop ...bie/develop/src/core/simplify.rkt:33:2 one-iter [9] 100.0% ---------------------------------------------------------------- ??? [7] 36.4% for-loop [8] 63.6% [9] 1032(4.7%) 0(0.0%) one-iter ...bie/develop/src/core/simplify.rkt:85:0 find-matches [10] 36.4% for-loop [11] 33.1% for-loop [12] 30.4% ---------------------------------------------------------------- one-iter [9] 100.0% [10] 376(1.7%) 0(0.0%) find-matches ...develop/src/core/simplify.rkt:55:0 for-loop [13] 100.0% ---------------------------------------------------------------- one-iter [9] 100.0% [11] 342(1.6%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:110:2 set-precompute! [14] 100.0% ---------------------------------------------------------------- one-iter [9] 100.0% [12] 314(1.4%) 0(0.0%) for-loop ...bie/develop/src/core/simplify.rkt:94:2 loop [15] 100.0% ---------------------------------------------------------------- for-loop [13] 50.0% find-matches [10] 50.0% [13] 376(1.7%) 0(0.0%) for-loop ...bie/develop/src/core/simplify.rkt:57:8 for-loop [13] 50.0% match-e [19] 50.0% ---------------------------------------------------------------- for-loop [11] 100.0% [14] 342(1.6%) 0(0.0%) set-precompute! ...lop/src/core/simplify.rkt:116:0 for-loop [16] 100.0% ---------------------------------------------------------------- for-loop [12] 100.0% [15] 314(1.4%) 0(0.0%) loop .../herbie/develop/src/core/simplify.rkt:99:4 apply-match [17] 100.0% ---------------------------------------------------------------- set-precompute! [14] 100.0% [16] 342(1.6%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:118:2 ??? [18] 100.0% ---------------------------------------------------------------- loop [15] 100.0% [17] 314(1.4%) 0(0.0%) apply-match .../develop/src/core/simplify.rkt:64:0 match-e [19] 100.0% ---------------------------------------------------------------- for-loop [16] 0.5% for-loop [5] 2.3% run [4] 48.6% [18] 21816(100.0%) 342(1.6%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 48.6% prepare-points [20] 39.2% errors [2] 2.9% oracle-error [21] 2.2% run-improve43 [3] 2.1% eval-errors [23] 1.5% ??? [44] 1.0% ??? [40] 0.9% errors-score [24] 0.6% ulp-difference [37] 0.4% ---------------------------------------------------------------- for-loop [27] 22.8% apply-match [17] 22.8% for-loop [13] 54.5% [19] 690(3.2%) 0(0.0%) match-e ...herbie/develop/src/core/ematch.rkt:39:0 for-loop [22] 77.2% ??? [36] 22.8% ---------------------------------------------------------------- ??? [18] 100.0% [20] 17112(78.4%) 0(0.0%) prepare-points ...bie/develop/src/points.rkt:166:0 loop [25] 100.0% ---------------------------------------------------------------- ??? [18] 100.0% [21] 964(4.4%) 0(0.0%) oracle-error ...erbie/develop/src/points.rkt:207:0 for-loop [26] 100.0% ---------------------------------------------------------------- match-e [19] 100.0% [22] 690(3.2%) 0(0.0%) for-loop ...rbie/develop/src/core/ematch.rkt:52:11 for-loop [27] 100.0% ---------------------------------------------------------------- ??? [18] 100.0% [23] 668(3.1%) 0(0.0%) eval-errors ...herbie/develop/src/points.rkt:198:0 for-loop [28] 100.0% ---------------------------------------------------------------- ??? [18] 100.0% [24] 278(1.3%) 0(0.0%) errors-score ...erbie/develop/src/points.rkt:215:0 loop [29] 100.0% ---------------------------------------------------------------- prepare-points [20] 100.0% [25] 17112(78.4%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:143:4 loop [30] 85.6% ??? [31] 12.7% ordinary-value? [33] 1.7% ---------------------------------------------------------------- oracle-error [21] 100.0% [26] 964(4.4%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:208:2 loop [32] 65.1% map [35] 34.9% ---------------------------------------------------------------- for-loop [22] 100.0% [27] 690(3.2%) 0(0.0%) for-loop ...rbie/develop/src/core/ematch.rkt:58:37 ??? [34] 54.5% match-e [19] 45.5% ---------------------------------------------------------------- eval-errors [23] 100.0% [28] 668(3.1%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:200:2 ??? [40] 100.0% ---------------------------------------------------------------- errors-score [24] 100.0% [29] 278(1.3%) 0(0.0%) loop .../racket-7.2/collects/racket/list.rkt:559:2 ordinary-value? [33] 100.0% ---------------------------------------------------------------- loop [25] 100.0% [30] 14648(67.1%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:102:2 ??? [40] 95.2% ??? [36] 2.7% ??? [59] 2.1% ---------------------------------------------------------------- loop [25] 100.0% [31] 2174(10.0%) 0(0.0%) ??? ...ghtlies/herbie/develop/src/points.rkt:127:2 ??? [44] 100.0% ---------------------------------------------------------------- for-loop [26] 100.0% [32] 628(2.9%) 318(1.5%) loop ...-7.2/collects/racket/private/map.rkt:40:19 ulp-difference [37] 49.4% ---------------------------------------------------------------- loop [29] 48.9% loop [25] 51.1% [33] 568(2.6%) 0(0.0%) ordinary-value? ...rbie/develop/src/float.rkt:80:0 special-value? [38] 100.0% ---------------------------------------------------------------- for-loop [27] 100.0% [34] 376(1.7%) 376(1.7%) ??? ...ies/herbie/develop/src/core/ematch.rkt:43:5 ---------------------------------------------------------------- for-loop [26] 100.0% [35] 336(1.5%) 0(0.0%) map ...t-7.2/collects/racket/private/map.rkt:35:13 ??? [39] 100.0% ---------------------------------------------------------------- match-e [19] 44.5% loop [30] 55.5% [36] 706(3.2%) 706(3.2%) ??? ...contract/private/arrow-val-first.rkt:390:18 ---------------------------------------------------------------- ??? [18] 46.6% loop [32] 53.4% [37] 580(2.7%) 0(0.0%) ulp-difference ...erbie/develop/src/float.rkt:46:0 infer-representation [41]100.0% ---------------------------------------------------------------- ordinary-value? [33] 100.0% [38] 568(2.6%) 0(0.0%) special-value? ...erbie/develop/src/float.rkt:76:0 for-loop [42] 100.0% ---------------------------------------------------------------- map [35] 100.0% [39] 336(1.5%) 0(0.0%) ??? ...htlies/herbie/develop/src/points.rkt:209:26 ??? [40] 100.0% ---------------------------------------------------------------- ??? [39] 2.2% ??? [18] 3.8% for-loop [28] 4.3% loop [30] 89.8% [40] 15540(71.2%) 336(1.5%) ??? ...htlies/herbie/develop/src/programs.rkt:99:2 ??? [43] 78.4% ??? [44] 19.4% ---------------------------------------------------------------- ulp-difference [37] 100.0% [41] 580(2.7%) 580(2.7%) infer-representation ...develop/src/float.rkt:15:0 ---------------------------------------------------------------- special-value? [38] 100.0% [42] 568(2.6%) 568(2.6%) for-loop ...lies/herbie/develop/src/float.rkt:17:3 ---------------------------------------------------------------- ??? [40] 100.0% [43] 12188(55.9%) 222(1.0%) ??? ...t-7.2/collects/racket/private/kw.rkt:594:14 ??? [44] 47.4% ??? [45] 42.7% ??? [46] 8.0% ---------------------------------------------------------------- ??? [18] 5.6% ??? [31] 18.7% ??? [40] 26.0% ??? [43] 49.8% [44] 11622(53.3%) 2856(13.1%) ??? ...tract/private/arrow-higher-order.rkt:360:33 ival-mult [47] 46.8% mk-ival [49] 18.3% ->flonum [51] 8.0% sample-multi-bounded [53] 2.4% ---------------------------------------------------------------- ??? [43] 100.0% [45] 5206(23.9%) 608(2.8%) ??? ...tract/private/arrow-higher-order.rkt:364:33 ival-div [48] 46.4% ival-sqrt [50] 29.8% ival-sub [52] 12.2% ---------------------------------------------------------------- ??? [43] 100.0% [46] 978(4.5%) 978(4.5%) ??? ...herbie/develop/src/syntax/syntax.rkt:209:17 ---------------------------------------------------------------- ??? [44] 100.0% [47] 5442(24.9%) 1046(4.8%) ival-mult ...bie/develop/src/biginterval.rkt:112:0 bfmul [54] 80.8% ---------------------------------------------------------------- ??? [45] 100.0% [48] 2414(11.1%) 888(4.1%) ival-div ...rbie/develop/src/biginterval.rkt:148:0 bfmul [54] 63.2% ---------------------------------------------------------------- ??? [44] 100.0% [49] 2122(9.7%) 0(0.0%) mk-ival ...herbie/develop/src/biginterval.rkt:57:0 bf [55] 100.0% ---------------------------------------------------------------- ??? [45] 100.0% [50] 1550(7.1%) 0(0.0%) ival-sqrt ...bie/develop/src/biginterval.rkt:198:0 bfsqrt [56] 100.0% ---------------------------------------------------------------- ??? [44] 100.0% [51] 926(4.2%) 276(1.3%) ->flonum ...ies/herbie/develop/src/float.rkt:149:0 ??? [57] 70.2% ---------------------------------------------------------------- ??? [45] 100.0% [52] 634(2.9%) 0(0.0%) ival-sub ...rbie/develop/src/biginterval.rkt:100:0 bfmul [54] 100.0% ---------------------------------------------------------------- ??? [44] 100.0% [53] 276(1.3%) 276(1.3%) sample-multi-bounded ...evelop/src/points.rkt:15:0 ---------------------------------------------------------------- ival-sub [52] 9.7% ival-div [48] 23.3% ival-mult [47] 67.1% [54] 6556(30.1%) 0(0.0%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [58] 73.1% ??? [59] 26.9% ---------------------------------------------------------------- mk-ival [49] 100.0% [55] 2122(9.7%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [58] 62.2% ??? [59] 37.8% ---------------------------------------------------------------- ival-sqrt [50] 100.0% [56] 1550(7.1%) 0(0.0%) bfsqrt ...lib/math/private/bigfloat/mpfr.rkt:618:4 new-mpfr [58] 50.5% ??? [59] 49.5% ---------------------------------------------------------------- ->flonum [51] 100.0% [57] 650(3.0%) 0(0.0%) ??? ...nightlies/herbie/develop/src/float.rkt:23:2 for-loop [60] 100.0% ---------------------------------------------------------------- bfsqrt [56] 11.3% bf [55] 19.1% bfmul [54] 69.5% [58] 6894(31.6%) 6894(31.6%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ---------------------------------------------------------------- loop [30] 8.4% bfsqrt [56] 21.1% bf [55] 22.0% bfmul [54] 48.5% [59] 3640(16.7%) 3640(16.7%) ??? /opt/racket-7.2/collects/ffi/unsafe.rkt:905:4 ---------------------------------------------------------------- ??? [57] 100.0% [60] 650(3.0%) 330(1.5%) for-loop ...lies/herbie/develop/src/float.rkt:24:5 return/no-unsupplied [61] 49.2% ---------------------------------------------------------------- for-loop [60] 100.0% [61] 320(1.5%) 320(1.5%) return/no-unsupplied ...ct/private/arr-i.rkt:542:0 ----------------------------------------------------------------