Profiling results ----------------- Total cpu time observed: 51082ms (out of 52156ms) Number of samples taken: 404 (once every 126ms) (Hiding functions with self<1.0% and local<2.0%: 2 of 67 hidden) ====================================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ====================================================================================== ??? [3] 100.0% [1] 50130(98.1%) 0(0.0%) profile-thunk16 ...t/pkgs/profile-lib/main.rkt:9:0 run [2] 100.0% -------------------------------------------------------------------------------------- profile-thunk16 [1] 99.3% [2] 50494(98.8%) 0(0.0%) run ...share/racket/pkgs/profile-lib/main.rkt:39:2 ??? [3] 100.0% -------------------------------------------------------------------------------------- run [2] 49.9% [3] 50972(99.8%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 49.2% loop [5] 49.1% run-improve50 [4] 1.7% -------------------------------------------------------------------------------------- ??? [3] 100.0% [4] 1554(3.0%) 0(0.0%) run-improve50 ...ock/herbie/src/mainloop.rkt:234:0 loop [5] 79.7% setup-prog [6] 20.3% -------------------------------------------------------------------------------------- run-improve50 [4] 2.4% ??? [3] 97.6% [5] 50656(99.2%) 172(0.3%) loop /home/ztatlock/herbie/src/points.rkt:159:2 loop [7] 43.1% make-exacts* [12] 37.5% loop [13] 17.9% for-loop [8] 1.0% filter-exacts [11] 0.1% -------------------------------------------------------------------------------------- run-improve50 [4] 100.0% [6] 316(0.6%) 0(0.0%) setup-prog /home/ztatlock/herbie/src/glue.rkt:45:0 simplify-alt [9] 50.6% ??? [10] 49.4% -------------------------------------------------------------------------------------- loop [5] 100.0% [7] 21848(42.8%) 0(0.0%) loop /home/ztatlock/herbie/src/points.rkt:119:2 make-exacts* [12] 47.5% loop [13] 47.0% eval-prog [19] 5.5% -------------------------------------------------------------------------------------- loop [5] 100.0% [8] 498(1.0%) 198(0.4%) for-loop .../ztatlock/herbie/src/points.rkt:166:24 for-loop [14] 60.2% -------------------------------------------------------------------------------------- setup-prog [6] 100.0% [9] 160(0.3%) 0(0.0%) simplify-alt .../ztatlock/herbie/src/glue.rkt:83:0 simplify [15] 100.0% -------------------------------------------------------------------------------------- setup-prog [6] 100.0% [10] 156(0.3%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:357:18 make-alt-table [16] 100.0% -------------------------------------------------------------------------------------- loop [5] 100.0% [11] 72(0.1%) 0(0.0%) filter-exacts ...tlock/herbie/src/points.rkt:134:0 for-loop [17] 100.0% -------------------------------------------------------------------------------------- loop [7] 35.3% loop [5] 64.7% [12] 29382(57.5%) 78(0.2%) make-exacts* ...atlock/herbie/src/points.rkt:106:0 map [18] 50.3% loop [23] 48.7% eval-prog [19] 0.7% -------------------------------------------------------------------------------------- loop [5] 46.8% loop [7] 53.2% [13] 19322(37.8%) 0(0.0%) loop /home/ztatlock/herbie/src/points.rkt:109:4 map [18] 100.0% -------------------------------------------------------------------------------------- for-loop [8] 100.0% [14] 300(0.6%) 94(0.2%) for-loop .../ztatlock/herbie/src/points.rkt:167:26 ??? [20] 68.7% -------------------------------------------------------------------------------------- simplify-alt [9] 100.0% [15] 160(0.3%) 0(0.0%) simplify ...lock/herbie/src/core/simplify.rkt:39:0 iterate-egraph!5 [21] 100.0% -------------------------------------------------------------------------------------- ??? [10] 100.0% [16] 156(0.3%) 0(0.0%) make-alt-table ...rbie/src/core/alt-table.rkt:32:0 errors [22] 100.0% -------------------------------------------------------------------------------------- filter-exacts [11] 100.0% [17] 72(0.1%) 72(0.1%) for-loop ...e/ztatlock/herbie/src/points.rkt:137:4 -------------------------------------------------------------------------------------- make-exacts* [12] 43.4% loop [13] 56.6% [18] 34112(66.8%) 0(0.0%) map ...acket/collects/racket/private/map.rkt:20:13 loop [23] 97.8% ??? [31] 2.2% -------------------------------------------------------------------------------------- make-exacts* [12] 14.2% loop [7] 85.8% [19] 1410(2.8%) 448(0.9%) eval-prog ...tatlock/herbie/src/programs.rkt:146:0 f134 [24] 29.2% ??? [33] 12.9% ??? [25] 11.9% go [29] 7.4% ??? [30] 6.8% -------------------------------------------------------------------------------------- for-loop [14] 100.0% [20] 206(0.4%) 64(0.1%) ??? /home/ztatlock/herbie/src/points.rkt:156:9 random-exp [28] 68.9% -------------------------------------------------------------------------------------- simplify [15] 100.0% [21] 160(0.3%) 0(0.0%) iterate-egraph!5 ...bie/src/core/simplify.rkt:95:0 one-iter [26] 100.0% -------------------------------------------------------------------------------------- make-alt-table [16] 100.0% [22] 156(0.3%) 0(0.0%) errors /home/ztatlock/herbie/src/points.rkt:184:0 for-loop [27] 100.0% -------------------------------------------------------------------------------------- make-exacts* [12] 0.7% map [18] 2.4% loop [23] 96.9% [23] 47772(93.5%) 1414(2.8%) loop ...cket/collects/racket/private/map.rkt:25:19 loop [23] 96.9% ??? [31] 2.9% ->flonum [32] 0.3% -------------------------------------------------------------------------------------- f134 [24] 27.2% eval-prog [19] 72.8% [24] 412(0.8%) 148(0.3%) f134 .../collects/racket/match/compiler.rkt:507:40 ??? [33] 36.9% f134 [24] 27.2% -------------------------------------------------------------------------------------- eval-prog [19] 100.0% [25] 168(0.3%) 168(0.3%) ??? ...cket/collects/racket/private/kw.rkt:1635:39 -------------------------------------------------------------------------------------- iterate-egraph!5 [21] 100.0% [26] 160(0.3%) 0(0.0%) one-iter ...ock/herbie/src/core/simplify.rkt:106:0 for-loop [34] 100.0% -------------------------------------------------------------------------------------- errors [22] 100.0% [27] 156(0.3%) 0(0.0%) for-loop ...e/ztatlock/herbie/src/points.rkt:187:4 ??? [31] 100.0% -------------------------------------------------------------------------------------- ??? [20] 100.0% [28] 142(0.3%) 142(0.3%) random-exp ...ztatlock/herbie/src/common.rkt:222:0 -------------------------------------------------------------------------------------- eval-prog [19] 100.0% [29] 104(0.2%) 104(0.2%) go .../collects/racket/private/qq-and-or.rkt:50:20 -------------------------------------------------------------------------------------- eval-prog [19] 100.0% [30] 96(0.2%) 0(0.0%) ??? ...cket/collects/racket/private/kw.rkt:1633:36 unpack242 [35] 100.0% -------------------------------------------------------------------------------------- for-loop [27] 0.4% map [18] 1.8% loop [23] 97.8% [31] 42254(82.7%) 2382(4.7%) ??? /home/ztatlock/herbie/src/programs.rkt:152:4 ??? [36] 64.4% ??? [37] 28.8% ??? [40] 1.1% -------------------------------------------------------------------------------------- loop [23] 100.0% [32] 5024(9.8%) 200(0.4%) ->flonum .../ztatlock/herbie/src/programs.rkt:34:0 ??? [38] 62.3% ??? [39] 33.8% -------------------------------------------------------------------------------------- eval-prog [19] 40.8% f134 [24] 59.2% [33] 446(0.9%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:342:33 ->bf [41] 40.8% table-ref [42] 37.7% ...erbie/src/common.rkt:46:2 [44] 21.5% -------------------------------------------------------------------------------------- for-loop [34] 50.0% one-iter [26] 50.0% [34] 160(0.3%) 0(0.0%) for-loop ...ck/herbie/src/core/simplify.rkt:116:12 for-loop [34] 50.0% match-e [43] 50.0% -------------------------------------------------------------------------------------- ??? [30] 100.0% [35] 96(0.2%) 0(0.0%) unpack242 ...private/arrow-higher-order.rkt:354:44 maybe-cons-kwd [45] 100.0% -------------------------------------------------------------------------------------- ??? [31] 100.0% [36] 27214(53.3%) 4824(9.4%) ??? ...acket/collects/racket/private/kw.rkt:444:14 ??? [46] 79.0% ??? [50] 1.3% -------------------------------------------------------------------------------------- ??? [31] 100.0% [37] 12184(23.9%) 1264(2.5%) ??? ...contract/private/../../private/kw.rkt:761:9 real->bigfloat [47] 89.6% -------------------------------------------------------------------------------------- ->flonum [32] 100.0% [38] 3128(6.1%) 1248(2.4%) ??? /home/ztatlock/herbie/src/config.rkt:48:0 ??? [48] 33.2% return/no-unsupplied [49] 26.9% -------------------------------------------------------------------------------------- ->flonum [32] 100.0% [39] 1696(3.3%) 300(0.6%) ??? ...-racket/utils/simple-result-arrow.rkt:60:15 ??? [63] 82.3% -------------------------------------------------------------------------------------- ??? [31] 100.0% [40] 474(0.9%) 280(0.5%) ??? ...tract/private/arrow-higher-order.rkt:145:21 ??? [57] 40.9% -------------------------------------------------------------------------------------- ??? [33] 100.0% [41] 182(0.4%) 0(0.0%) ->bf /home/ztatlock/herbie/src/programs.rkt:49:0 ??? [51] 100.0% -------------------------------------------------------------------------------------- ??? [33] 100.0% [42] 168(0.3%) 0(0.0%) table-ref .../racket/private/norm-define.rkt:53:83 ??? [52] 100.0% -------------------------------------------------------------------------------------- for-loop [34] 100.0% [43] 160(0.3%) 160(0.3%) match-e ...tatlock/herbie/src/core/ematch.rkt:46:0 -------------------------------------------------------------------------------------- ??? [33] 100.0% [44] 96(0.2%) 96(0.2%) ...erbie/src/common.rkt:46:2 ...e/arr-i.rkt:906:19 -------------------------------------------------------------------------------------- unpack242 [35] 100.0% [45] 96(0.2%) 0(0.0%) maybe-cons-kwd ...ate/arrow-higher-order.rkt:498:0 successfully-got-the-right-kind-of-function [53]100.0% -------------------------------------------------------------------------------------- ??? [36] 100.0% [46] 21488(42.1%) 8722(17.1%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bflog [54] 43.0% bfadd [55] 16.4% -------------------------------------------------------------------------------------- ??? [37] 100.0% [47] 10920(21.4%) 7164(14.0%) real->bigfloat .../private/bigfloat/mpfr.rkt:451:0 ??? [63] 34.4% -------------------------------------------------------------------------------------- ??? [38] 100.0% [48] 1038(2.0%) 192(0.4%) ??? ...racket/collects/racket/private/kw.rkt:761:9 ??? [56] 67.6% ??? [57] 13.9% -------------------------------------------------------------------------------------- ??? [38] 100.0% [49] 842(1.6%) 532(1.0%) return/no-unsupplied ...ct/private/arr-i.rkt:533:0 filter [58] 36.8% -------------------------------------------------------------------------------------- ??? [36] 100.0% [50] 352(0.7%) 132(0.3%) ??? ...ects/racket/contract/private/prop.rkt:493:4 ??? [59] 62.5% -------------------------------------------------------------------------------------- ->bf [41] 100.0% [51] 182(0.4%) 0(0.0%) ??? ...ts/racket/contract/private/arr-i.rkt:804:10 ??? [60] 100.0% -------------------------------------------------------------------------------------- table-ref [42] 100.0% [52] 168(0.3%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:737:4 for-loop [61] 100.0% -------------------------------------------------------------------------------------- maybe-cons-kwd [45] 100.0% [53] 96(0.2%) 0(0.0%) successfully-got-the-right-kind-of-function ...9:4 arity-checking-wrapper [62] 100.0% -------------------------------------------------------------------------------------- ??? [46] 100.0% [54] 9250(18.1%) 5888(11.5%) bflog ...-lib/math/private/bigfloat/mpfr.rkt:616:4 ??? [63] 36.3% -------------------------------------------------------------------------------------- ??? [46] 100.0% [55] 3516(6.9%) 3002(5.9%) bfadd ...-lib/math/private/bigfloat/mpfr.rkt:819:4 ??? [63] 14.6% -------------------------------------------------------------------------------------- ??? [48] 100.0% [56] 702(1.4%) 702(1.4%) ??? ...s/racket/contract/private/arr-i.rkt:1233:27 -------------------------------------------------------------------------------------- ??? [48] 42.6% ??? [40] 57.4% [57] 338(0.7%) 338(0.7%) ??? ...ects/racket/contract/private/guts.rkt:644:8 -------------------------------------------------------------------------------------- return/no-unsupplied [49] 100.0% [58] 310(0.6%) 310(0.6%) filter ...t/collects/racket/private/list.rkt:256:2 -------------------------------------------------------------------------------------- ??? [50] 100.0% [59] 220(0.4%) 220(0.4%) ??? ...lects/racket/contract/private/orc.rkt:83:14 -------------------------------------------------------------------------------------- ??? [51] 100.0% [60] 182(0.4%) 88(0.2%) ??? ...ntract/private/arrow-higher-order.rkt:518:2 get/build-late-neg-projection [65] 51.6% -------------------------------------------------------------------------------------- ??? [52] 100.0% [61] 168(0.3%) 168(0.3%) for-loop ...acket/contract/private/list.rkt:743:12 -------------------------------------------------------------------------------------- successfully-got-the-right-kind-of-function [53]100.0% [62] 96(0.2%) 0(0.0%) arity-checking-wrapper ...w-higher-order.rkt:402:0 matches-arity-exactly? [64] 100.0% -------------------------------------------------------------------------------------- bfadd [55] 5.7% ??? [39] 15.5% bflog [54] 37.2% real->bigfloat [47] 41.6% [63] 9028(17.7%) 9028(17.7%) ??? .../share/racket/collects/ffi/unsafe.rkt:861:4 -------------------------------------------------------------------------------------- arity-checking-wrapper [62] 100.0% [64] 96(0.2%) 96(0.2%) matches-arity-exactly? ...te/arrow-common.rkt:70:0 -------------------------------------------------------------------------------------- ??? [60] 100.0% [65] 94(0.2%) 94(0.2%) get/build-late-neg-projection ...te/guts.rkt:691:0 --------------------------------------------------------------------------------------