Profiling results ----------------- Total cpu time observed: 11354ms (out of 11936ms) Number of samples taken: 32 (once every 355ms) ======================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ======================================================================== run [12] 47.0% [1] 11354(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [5] 47.0% prepare-points [6] 38.2% run-improve50 [8] 14.8% ------------------------------------------------------------------------ loop [25] 100.0% [2] 8274(72.9%) 0(0.0%) ??? ...tlies/herbie/develop/src/programs.rkt:156:4 ??? [7] 100.0% ------------------------------------------------------------------------ match-e [36] 100.0% [3] 690(6.1%) 0(0.0%) for-loop ...rbie/develop/src/core/ematch.rkt:59:11 for-loop [9] 75.8% list-cartesian-product [10] 24.2% ------------------------------------------------------------------------ ??? [28] 100.0% [4] 312(2.7%) 0(0.0%) loop ...herbie/develop/src/core/simplify.rkt:237:2 pass [11] 100.0% ------------------------------------------------------------------------ ??? [1] 100.0% [5] 10662(93.9%) 0(0.0%) profile-thunk16 ...t/pkgs/profile-lib/main.rkt:9:0 run [12] 100.0% ------------------------------------------------------------------------ ??? [1] 100.0% [6] 8680(76.4%) 0(0.0%) prepare-points ...bie/develop/src/points.rkt:214:0 make-exacts* [20] 36.6% loop [14] 31.9% loop [21] 31.5% ------------------------------------------------------------------------ ??? [2] 100.0% [7] 8274(72.9%) 1472(13.0%) ??? ...acket/collects/racket/private/kw.rkt:444:14 bf* [13] 39.7% ??? [19] 30.1% ??? [16] 8.6% ...higher-order.rkt:346:33 [17] 3.8% ------------------------------------------------------------------------ ??? [1] 100.0% [8] 2674(23.6%) 0(0.0%) run-improve50 ...ie/develop/src/mainloop.rkt:234:0 setup-alt-simplified [15] 100.0% ------------------------------------------------------------------------ match-e [36] 34.3% for-loop [3] 65.7% [9] 1050(9.2%) 0(0.0%) for-loop ...rbie/develop/src/core/ematch.rkt:65:37 match-e [36] 100.0% ------------------------------------------------------------------------ for-loop [3] 100.0% [10] 334(2.9%) 334(2.9%) list-cartesian-product ...src/core/ematch.rkt:20:0 ------------------------------------------------------------------------ loop [4] 100.0% [11] 312(2.7%) 0(0.0%) pass ...herbie/develop/src/core/simplify.rkt:227:2 for-loop [18] 100.0% ------------------------------------------------------------------------ profile-thunk16 [5] 100.0% [12] 10662(93.9%) 0(0.0%) run ...share/racket/pkgs/profile-lib/main.rkt:39:2 ??? [1] 100.0% ------------------------------------------------------------------------ ??? [7] 100.0% [13] 3284(28.9%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [19] 100.0% ------------------------------------------------------------------------ prepare-points [6] 100.0% [14] 2772(24.4%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:158:2 make-exacts* [20] 64.1% loop [21] 35.9% ------------------------------------------------------------------------ run-improve50 [8] 100.0% [15] 2674(23.6%) 0(0.0%) setup-alt-simplified .../develop/src/glue.rkt:49:0 simplify-alt [22] 100.0% ------------------------------------------------------------------------ ??? [7] 100.0% [16] 714(6.3%) 714(6.3%) ??? ...rivate/unconstrained-domain-arrow.rkt:40:13 ------------------------------------------------------------------------ ??? [7] 100.0% [17] 316(2.8%) 0(0.0%) ...higher-order.rkt:346:33 (unknown source) ??? [23] 100.0% ------------------------------------------------------------------------ pass [11] 100.0% [18] 312(2.7%) 0(0.0%) for-loop ...e/develop/src/core/simplify.rkt:230:29 argmin [24] 100.0% ------------------------------------------------------------------------ ??? [7] 43.1% bf* [13] 56.9% [19] 5772(50.8%) 362(3.2%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [26] 93.7% ------------------------------------------------------------------------ loop [14] 35.9% prepare-points [6] 64.1% [20] 4952(43.6%) 0(0.0%) make-exacts* ...erbie/develop/src/points.rkt:138:0 loop [25] 100.0% ------------------------------------------------------------------------ loop [14] 26.7% prepare-points [6] 73.3% [21] 3728(32.8%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:141:4 loop [25] 100.0% ------------------------------------------------------------------------ setup-alt-simplified [15] 100.0% [22] 2674(23.6%) 0(0.0%) simplify-alt ...s/herbie/develop/src/glue.rkt:76:0 ??? [28] 100.0% ------------------------------------------------------------------------ ...higher-order.rkt:346:33 [17] 49.5% ??? [47] 50.5% [23] 638(5.6%) 316(2.8%) ??? ...cts/racket/contract/private/list.rkt:187:10 ??? [47] 50.5% ------------------------------------------------------------------------ for-loop [18] 100.0% [24] 312(2.7%) 0(0.0%) argmin ...re/racket/collects/racket/list.rkt:743:0 for-loop [27] 100.0% ------------------------------------------------------------------------ loop [21] 0.1% loop! [37] 0.3% make-exacts* [20] 1.5% loop [25] 98.1% [25] 9014(79.4%) 0(0.0%) loop ...cket/collects/racket/private/map.rkt:25:19 loop [25] 98.1% ??? [2] 1.6% loop! [37] 0.3% ??? [30] 0.0% ------------------------------------------------------------------------ ??? [19] 100.0% [26] 5410(47.6%) 3962(34.9%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:819:4 ??? [29] 26.8% ------------------------------------------------------------------------ argmin [24] 100.0% [27] 312(2.7%) 0(0.0%) for-loop .../herbie/develop/src/programs.rkt:210:2 ??? [28] 100.0% ------------------------------------------------------------------------ for-loop [27] 3.9% ??? [28] 48.1% simplify-alt [22] 48.1% [28] 2674(23.6%) 0(0.0%) ??? ...llects/racket/private/norm-define.rkt:53:83 ??? [28] 48.1% iterate-egraph!13 [31] 44.2% loop [4] 3.9% ??? [48] 3.9% ------------------------------------------------------------------------ bfmul [26] 100.0% [29] 1448(12.8%) 1448(12.8%) ??? .../share/racket/collects/ffi/unsafe.rkt:861:4 ------------------------------------------------------------------------ loop [25] 100.0% [30] 406(3.6%) 406(3.6%) ??? ...tract/private/arrow-higher-order.rkt:342:33 ------------------------------------------------------------------------ ??? [28] 100.0% [31] 2362(20.8%) 0(0.0%) iterate-egraph!13 ...p/src/core/simplify.rkt:105:0 one-iter [32] 100.0% ------------------------------------------------------------------------ iterate-egraph!13 [31] 100.0% [32] 2362(20.8%) 0(0.0%) one-iter ...ie/develop/src/core/simplify.rkt:116:0 for-loop [33] 69.7% for-loop [34] 30.3% ------------------------------------------------------------------------ one-iter [32] 100.0% [33] 1646(14.5%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:166:2 for-loop [35] 79.7% match-e [36] 20.3% ------------------------------------------------------------------------ for-loop [34] 50.0% one-iter [32] 50.0% [34] 716(6.3%) 0(0.0%) for-loop ...e/develop/src/core/simplify.rkt:126:12 for-loop [34] 50.0% match-e [36] 50.0% ------------------------------------------------------------------------ for-loop [33] 100.0% [35] 1312(11.6%) 0(0.0%) for-loop ...e/develop/src/core/simplify.rkt:148:10 loop! [37] 50.8% mk-enode! [38] 49.2% ------------------------------------------------------------------------ for-loop [33] 15.9% for-loop [34] 28.4% for-loop [9] 55.7% [36] 1050(9.2%) 0(0.0%) match-e ...herbie/develop/src/core/ematch.rkt:46:0 for-loop [3] 54.4% ??? [44] 28.4% for-loop [9] 17.1% ------------------------------------------------------------------------ loop [25] 25.1% for-loop [35] 74.9% [37] 666(5.9%) 334(2.9%) loop! ...s/herbie/develop/src/core/enode.rkt:192:2 custom-set-union [40] 49.8% loop [25] 25.1% ------------------------------------------------------------------------ for-loop [35] 100.0% [38] 646(5.7%) 0(0.0%) mk-enode! ...bie/develop/src/core/egraph.rkt:102:0 new-enode [39] 100.0% ------------------------------------------------------------------------ mk-enode! [38] 100.0% [39] 646(5.7%) 0(0.0%) new-enode ...erbie/develop/src/core/enode.rkt:97:0 type-of-enode-expr [41] 100.0% ------------------------------------------------------------------------ loop! [37] 100.0% [40] 332(2.9%) 0(0.0%) custom-set-union ...et/private/set-types.rkt:168:0 for-loop [42] 100.0% ------------------------------------------------------------------------ new-enode [39] 100.0% [41] 646(5.7%) 0(0.0%) type-of-enode-expr ...elop/src/core/enode.rkt:68:0 get-sigs [43] 100.0% ------------------------------------------------------------------------ custom-set-union [40] 100.0% [42] 332(2.9%) 332(2.9%) for-loop ...cts/racket/private/set-types.rkt:152:2 ------------------------------------------------------------------------ type-of-enode-expr [41] 100.0% [43] 646(5.7%) 0(0.0%) get-sigs .../herbie/develop/src/type-check.rkt:5:0 ??? [44] 50.2% ...velop/src/common.rkt:47:2 [45] 49.8% ------------------------------------------------------------------------ get-sigs [43] 31.2% match-e [36] 68.8% [44] 1040(9.2%) 716(6.3%) ??? ...contract/private/arrow-val-first.rkt:357:18 hash-has-key? [46] 31.2% ------------------------------------------------------------------------ get-sigs [43] 100.0% [45] 322(2.8%) 0(0.0%) ...velop/src/common.rkt:47:2 ...e/arr-i.rkt:906:19 ??? [47] 100.0% ------------------------------------------------------------------------ ??? [44] 100.0% [46] 324(2.9%) 0(0.0%) hash-has-key? ...et/private/more-scheme.rkt:368:13 ??? [48] 100.0% ------------------------------------------------------------------------ ??? [23] 50.0% ...velop/src/common.rkt:47:2 [45] 50.0% [47] 322(2.8%) 322(2.8%) ??? ...ects/racket/contract/private/list.rkt:342:4 ??? [23] 50.0% ------------------------------------------------------------------------ ??? [28] 49.1% hash-has-key? [46] 50.9% [48] 636(5.6%) 636(5.6%) ??? ...ects/racket/contract/private/list.rkt:737:4 ------------------------------------------------------------------------