Profiling results ----------------- Total cpu time observed: 18766ms (out of 19440ms) Number of samples taken: 47 (once every 399ms) ================================================================ Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ================================================================ run [6] 50.0% [1] 18362(97.8%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [3] 50.0% loop [7] 46.1% run-improve50 [5] 3.9% ---------------------------------------------------------------- get-final-combination [10] 21.0% loop [25] 79.0% [2] 1714(9.1%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:342:33 ??? [4] 100.0% ---------------------------------------------------------------- ??? [1] 100.0% [3] 18362(97.8%) 0(0.0%) profile-thunk16 ...t/pkgs/profile-lib/main.rkt:9:0 run [6] 100.0% ---------------------------------------------------------------- simplify-alt [16] 8.6% ??? [4] 17.3% ??? [2] 74.2% [4] 2068(11.0%) 326(1.7%) ??? ...llects/racket/private/norm-define.rkt:53:83 ??? [9] 32.4% ??? [11] 17.3% ??? [4] 17.3% iterate-egraph!13 [8] 17.3% ---------------------------------------------------------------- ??? [1] 100.0% [5] 1432(7.6%) 0(0.0%) run-improve50 ...ie/develop/src/mainloop.rkt:234:0 loop [7] 50.1% get-final-combination [10] 25.1% setup-alt-simplified [12] 24.7% ---------------------------------------------------------------- profile-thunk16 [3] 100.0% [6] 18362(97.8%) 0(0.0%) run ...share/racket/pkgs/profile-lib/main.rkt:39:2 ??? [1] 100.0% ---------------------------------------------------------------- run-improve50 [5] 4.1% ??? [1] 95.9% [7] 17648(94.0%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:193:2 loop [13] 45.9% make-exacts* [17] 41.5% loop [18] 12.6% ---------------------------------------------------------------- ??? [4] 100.0% [8] 714(3.8%) 0(0.0%) iterate-egraph!13 ...p/src/core/simplify.rkt:102:0 one-iter [14] 100.0% ---------------------------------------------------------------- ??? [4] 100.0% [9] 670(3.6%) 316(1.7%) ??? ...-racket/utils/simple-result-arrow.rkt:60:15 ??? [40] 52.8% ---------------------------------------------------------------- run-improve50 [5] 100.0% [10] 360(1.9%) 0(0.0%) get-final-combination ...op/src/mainloop.rkt:264:0 ??? [2] 100.0% ---------------------------------------------------------------- ??? [4] 100.0% [11] 358(1.9%) 0(0.0%) ??? ...racket/collects/racket/private/kw.rkt:761:9 ??? [15] 100.0% ---------------------------------------------------------------- run-improve50 [5] 100.0% [12] 354(1.9%) 0(0.0%) setup-alt-simplified .../develop/src/glue.rkt:49:0 simplify-alt [16] 100.0% ---------------------------------------------------------------- loop [7] 100.0% [13] 8106(43.2%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:151:2 loop [18] 49.9% make-exacts* [17] 45.7% eval-prog [20] 4.4% ---------------------------------------------------------------- iterate-egraph!13 [8] 100.0% [14] 714(3.8%) 0(0.0%) one-iter ...ie/develop/src/core/simplify.rkt:113:0 for-loop [19] 50.4% loop [21] 49.6% ---------------------------------------------------------------- ??? [11] 100.0% [15] 358(1.9%) 358(1.9%) ??? ...ects/racket/contract/private/guts.rkt:644:8 ---------------------------------------------------------------- setup-alt-simplified [12] 100.0% [16] 354(1.9%) 0(0.0%) simplify-alt ...s/herbie/develop/src/glue.rkt:76:0 ??? [4] 100.0% ---------------------------------------------------------------- loop [13] 33.6% loop [7] 66.4% [17] 11022(58.7%) 0(0.0%) make-exacts* ...erbie/develop/src/points.rkt:138:0 map [22] 66.5% loop [25] 33.5% ---------------------------------------------------------------- loop [7] 35.4% loop [13] 64.6% [18] 6268(33.4%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:141:4 map [22] 100.0% ---------------------------------------------------------------- for-loop [19] 50.0% one-iter [14] 50.0% [19] 360(1.9%) 0(0.0%) for-loop ...e/develop/src/core/simplify.rkt:123:12 for-loop [19] 50.0% match-e [23] 50.0% ---------------------------------------------------------------- loop [13] 100.0% [20] 358(1.9%) 358(1.9%) eval-prog ...herbie/develop/src/programs.rkt:148:0 ---------------------------------------------------------------- one-iter [14] 100.0% [21] 354(1.9%) 0(0.0%) loop ...cket/collects/racket/private/map.rkt:52:19 apply-match [24] 100.0% ---------------------------------------------------------------- loop [18] 46.1% make-exacts* [17] 53.9% [22] 13598(72.5%) 0(0.0%) map ...acket/collects/racket/private/map.rkt:20:13 loop [25] 100.0% ---------------------------------------------------------------- for-loop [19] 100.0% [23] 360(1.9%) 360(1.9%) match-e ...herbie/develop/src/core/ematch.rkt:46:0 ---------------------------------------------------------------- loop [21] 100.0% [24] 354(1.9%) 0(0.0%) apply-match ...develop/src/core/simplify.rkt:130:2 for-loop [26] 100.0% ---------------------------------------------------------------- make-exacts* [17] 0.5% loop! [31] 2.0% map [22] 2.2% loop [25] 95.3% [25] 18048(96.2%) 0(0.0%) loop ...cket/collects/racket/private/map.rkt:25:19 loop [25] 95.3% ??? [27] 2.3% loop! [31] 2.0% ??? [2] 0.4% ---------------------------------------------------------------- apply-match [24] 100.0% [26] 354(1.9%) 0(0.0%) for-loop ...e/develop/src/core/simplify.rkt:145:10 merge-egraph-nodes! [28] 100.0% ---------------------------------------------------------------- loop [25] 100.0% [27] 16340(87.1%) 672(3.6%) ??? ...tlies/herbie/develop/src/programs.rkt:154:4 ??? [29] 79.8% ??? [30] 16.1% ---------------------------------------------------------------- for-loop [26] 100.0% [28] 354(1.9%) 0(0.0%) merge-egraph-nodes! ...p/src/core/egraph.rkt:152:0 loop! [31] 100.0% ---------------------------------------------------------------- ??? [27] 100.0% [29] 13034(69.5%) 4438(23.6%) ??? ...acket/collects/racket/private/kw.rkt:444:14 ??? [37] 49.1% bf* [33] 11.5% ??? [34] 2.7% parse-loop145 [36] 2.6% ---------------------------------------------------------------- ??? [27] 100.0% [30] 2634(14.0%) 350(1.9%) ??? ...contract/private/../../private/kw.rkt:761:9 real->bigfloat [32] 86.7% ---------------------------------------------------------------- loop [25] 50.0% merge-egraph-nodes! [28] 50.0% [31] 354(1.9%) 0(0.0%) loop! ...s/herbie/develop/src/core/enode.rkt:192:2 loop [25] 50.0% set [35] 50.0% ---------------------------------------------------------------- ??? [30] 100.0% [32] 2284(12.2%) 2284(12.2%) real->bigfloat .../private/bigfloat/mpfr.rkt:451:0 ---------------------------------------------------------------- ??? [29] 100.0% [33] 1502(8.0%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [37] 100.0% ---------------------------------------------------------------- ??? [29] 100.0% [34] 358(1.9%) 358(1.9%) ??? ...rivate/unconstrained-domain-arrow.rkt:40:13 ---------------------------------------------------------------- loop! [31] 100.0% [35] 354(1.9%) 354(1.9%) set ...collects/racket/private/set-types.rkt:981:0 ---------------------------------------------------------------- ??? [29] 100.0% [36] 340(1.8%) 340(1.8%) parse-loop145 .../racket/match/compiler.rkt:418:15 ---------------------------------------------------------------- bf* [33] 19.0% ??? [29] 81.0% [37] 7898(42.1%) 1778(9.5%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [38] 54.1% bfexp [39] 23.4% ---------------------------------------------------------------- ??? [37] 100.0% [38] 4270(22.8%) 3394(18.1%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:819:4 ??? [40] 20.5% ---------------------------------------------------------------- ??? [37] 100.0% [39] 1850(9.9%) 706(3.8%) bfexp ...-lib/math/private/bigfloat/mpfr.rkt:616:4 ??? [40] 61.8% ---------------------------------------------------------------- ??? [9] 14.9% bfmul [38] 36.9% bfexp [39] 48.2% [40] 2374(12.7%) 2374(12.7%) ??? .../share/racket/collects/ffi/unsafe.rkt:861:4 ----------------------------------------------------------------