Profiling results ----------------- Total cpu time observed: 5266ms (out of 5368ms) Number of samples taken: 28 (once every 188ms) ========================================================================= Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ========================================================================= run [7] 50.0% [1] 5266(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [4] 50.0% prepare-points [13] 19.8% run-improve39 [5] 19.1% loop [15] 11.1% ------------------------------------------------------------------------- hash-ref! [61] 100.0% [2] 684(13.0%) 0(0.0%) ??? ...tlies/herbie/develop/src/programs.rkt:153:5 loop [25] 100.0% ------------------------------------------------------------------------- loop [25] 100.0% [3] 302(5.7%) 0(0.0%) set-precompute! ...lop/src/core/simplify.rkt:140:0 for-loop [6] 100.0% ------------------------------------------------------------------------- ??? [1] 100.0% [4] 5266(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [7] 100.0% ------------------------------------------------------------------------- ??? [1] 100.0% [5] 2010(38.2%) 0(0.0%) run-improve39 ...ie/develop/src/mainloop.rkt:286:0 for-loop [8] 75.9% setup-prog!32 [9] 16.6% for-loop [11] 7.5% ------------------------------------------------------------------------- set-precompute! [3] 100.0% [6] 302(5.7%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:142:2 eval-const-expr [10] 50.3% reduce-to-new! [12] 49.7% ------------------------------------------------------------------------- profile-thunk16 [4] 100.0% [7] 5266(100.0%) 0(0.0%) run ...racket/share/pkgs/profile-lib/main.rkt:39:2 ??? [1] 100.0% ------------------------------------------------------------------------- run-improve39 [5] 100.0% [8] 1526(29.0%) 0(0.0%) for-loop ...herbie/develop/src/mainloop.rkt:298:29 ??? [14] 100.0% ------------------------------------------------------------------------- run-improve39 [5] 100.0% [9] 334(6.3%) 0(0.0%) setup-prog!32 ...bie/develop/src/mainloop.rkt:63:0 prepare-points [13] 51.5% loop [15] 48.5% ------------------------------------------------------------------------- for-loop [6] 100.0% [10] 152(2.9%) 0(0.0%) eval-const-expr .../develop/src/programs.rkt:132:0 eval-prog [55] 100.0% ------------------------------------------------------------------------- run-improve39 [5] 100.0% [11] 150(2.8%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:303:8 run-iter! [16] 100.0% ------------------------------------------------------------------------- for-loop [6] 100.0% [12] 150(2.8%) 0(0.0%) reduce-to-new! ...evelop/src/core/egraph.rkt:337:0 merge-egraph-nodes! [35] 100.0% ------------------------------------------------------------------------- setup-prog!32 [9] 7.6% ??? [1] 92.4% [13] 2254(42.8%) 0(0.0%) prepare-points ...bie/develop/src/points.rkt:156:0 loop [20] 45.5% loop [18] 30.7% make-exacts* [21] 23.8% ------------------------------------------------------------------------- for-loop [8] 100.0% [14] 1526(29.0%) 0(0.0%) ??? ...s/herbie/develop/src/core/simplify.rkt:31:0 iterate-egraph!7 [17] 100.0% ------------------------------------------------------------------------- setup-prog!32 [9] 12.1% ??? [1] 87.9% [15] 1336(25.4%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:182:2 loop [18] 50.6% make-exacts* [21] 36.8% loop [20] 12.6% ------------------------------------------------------------------------- for-loop [11] 100.0% [16] 150(2.8%) 0(0.0%) run-iter! ...herbie/develop/src/mainloop.rkt:267:0 choose-best-alt! [19] 100.0% ------------------------------------------------------------------------- ??? [14] 100.0% [17] 1526(29.0%) 0(0.0%) iterate-egraph!7 ...lop/src/core/simplify.rkt:55:0 one-iter [22] 80.2% map-enodes [23] 19.8% ------------------------------------------------------------------------- loop [15] 49.4% prepare-points [13] 50.6% [18] 1368(26.0%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:124:2 loop [20] 61.3% make-exacts* [21] 38.7% ------------------------------------------------------------------------- run-iter! [16] 100.0% [19] 150(2.8%) 0(0.0%) choose-best-alt! ...develop/src/mainloop.rkt:113:0 ??? [24] 100.0% ------------------------------------------------------------------------- loop [15] 8.3% loop [18] 41.2% prepare-points [13] 50.5% [20] 2032(38.6%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:107:4 loop [25] 100.0% ------------------------------------------------------------------------- loop [15] 31.6% loop [18] 34.0% prepare-points [13] 34.4% [21] 1558(29.6%) 0(0.0%) make-exacts* ...erbie/develop/src/points.rkt:104:0 eval-prog [55] 54.9% loop [25] 45.1% ------------------------------------------------------------------------- iterate-egraph!7 [17] 100.0% [22] 1224(23.2%) 0(0.0%) one-iter ...bie/develop/src/core/simplify.rkt:66:0 for-loop [26] 87.9% for-loop [28] 12.1% ------------------------------------------------------------------------- iterate-egraph!7 [17] 100.0% [23] 302(5.7%) 0(0.0%) map-enodes ...ie/develop/src/core/egraph.rkt:140:0 loop [25] 100.0% ------------------------------------------------------------------------- choose-best-alt! [19] 100.0% [24] 150(2.8%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:388:18 ??? [27] 100.0% ------------------------------------------------------------------------- make-exacts* [21] 0.0% loop! [46] 0.1% map-enodes [23] 0.5% loop [20] 5.9% ??? [2] 17.7% loop [25] 75.8% [25] 3868(73.5%) 506(9.6%) loop ...cket/collects/racket/private/map.rkt:40:19 loop [25] 75.8% hash-ref! [61] 17.7% ??? [29] 5.9% set-precompute! [3] 0.5% loop! [46] 0.1% ------------------------------------------------------------------------- one-iter [22] 100.0% [26] 1076(20.4%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:113:2 for-loop [30] 86.4% update-leader! [41] 13.6% ------------------------------------------------------------------------- ??? [24] 100.0% [27] 150(2.8%) 0(0.0%) ??? .../herbie/develop/src/core/alt-table.rkt:54:0 ??? [31] 100.0% ------------------------------------------------------------------------- for-loop [28] 50.0% one-iter [22] 50.0% [28] 148(2.8%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:76:12 for-loop [28] 50.0% match-e [32] 50.0% ------------------------------------------------------------------------- loop [25] 100.0% [29] 2228(42.3%) 166(3.2%) ??? ...tlies/herbie/develop/src/programs.rkt:129:2 ??? [33] 54.3% bf [34] 38.2% ------------------------------------------------------------------------- for-loop [26] 100.0% [30] 930(17.7%) 0(0.0%) for-loop ...bie/develop/src/core/simplify.rkt:98:4 substitute-e [36] 33.8% mk-enode! [45] 33.8% merge-egraph-nodes! [35] 32.5% ------------------------------------------------------------------------- ??? [27] 100.0% [31] 150(2.8%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:357:33 best-alt [37] 100.0% ------------------------------------------------------------------------- for-loop [28] 100.0% [32] 148(2.8%) 0(0.0%) match-e ...herbie/develop/src/core/ematch.rkt:46:0 for-loop [38] 100.0% ------------------------------------------------------------------------- ??? [29] 100.0% [33] 1210(23.0%) 712(13.5%) ??? ...acket/collects/racket/private/kw.rkt:592:14 bf* [39] 27.8% ??? [44] 13.4% ------------------------------------------------------------------------- ??? [29] 100.0% [34] 852(16.2%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [74] 100.0% ------------------------------------------------------------------------- reduce-to-new! [12] 33.2% for-loop [30] 66.8% [35] 452(8.6%) 0(0.0%) merge-egraph-nodes! ...p/src/core/egraph.rkt:153:0 for-loop [40] 66.8% update-leader! [41] 33.2% ------------------------------------------------------------------------- for-loop [30] 100.0% [36] 314(6.0%) 162(3.1%) substitute-e ...e/develop/src/core/ematch.rkt:71:0 for-loop [42] 48.4% ------------------------------------------------------------------------- ??? [31] 100.0% [37] 150(2.8%) 0(0.0%) best-alt .../herbie/develop/src/mainloop.rkt:108:0 argmins [43] 100.0% ------------------------------------------------------------------------- match-e [32] 100.0% [38] 148(2.8%) 148(2.8%) for-loop ...rbie/develop/src/core/ematch.rkt:59:11 ------------------------------------------------------------------------- ??? [33] 100.0% [39] 336(6.4%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [44] 100.0% ------------------------------------------------------------------------- merge-egraph-nodes! [35] 100.0% [40] 302(5.7%) 0(0.0%) for-loop ...bie/develop/src/core/egraph.rkt:201:10 loop! [46] 100.0% ------------------------------------------------------------------------- for-loop [26] 49.3% merge-egraph-nodes! [35] 50.7% [41] 296(5.6%) 0(0.0%) update-leader! ...evelop/src/core/egraph.rkt:218:0 for-loop [47] 100.0% ------------------------------------------------------------------------- substitute-e [36] 100.0% [42] 152(2.9%) 0(0.0%) for-loop ...rbie/develop/src/core/ematch.rkt:80:24 mk-enode! [45] 100.0% ------------------------------------------------------------------------- best-alt [37] 100.0% [43] 150(2.8%) 0(0.0%) argmins ...ies/herbie/develop/src/common.rkt:142:0 ??? [48] 100.0% ------------------------------------------------------------------------- ??? [33] 32.5% bf* [39] 67.5% [44] 498(9.5%) 0(0.0%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfsub [49] 100.0% ------------------------------------------------------------------------- for-loop [42] 32.6% for-loop [30] 67.4% [45] 466(8.8%) 0(0.0%) mk-enode! ...bie/develop/src/core/egraph.rkt:102:0 new-enode [50] 100.0% ------------------------------------------------------------------------- loop [25] 24.5% for-loop [40] 75.5% [46] 302(5.7%) 0(0.0%) loop! ...s/herbie/develop/src/core/enode.rkt:190:2 custom-set-union [52] 51.0% loop [25] 24.5% update-en-expr [54] 24.5% ------------------------------------------------------------------------- update-leader! [41] 100.0% [47] 296(5.6%) 0(0.0%) for-loop ...rbie/develop/src/core/egraph.rkt:223:6 for-loop [51] 100.0% ------------------------------------------------------------------------- argmins [43] 100.0% [48] 150(2.8%) 0(0.0%) ??? ...lies/herbie/develop/src/mainloop.rkt:110:19 errors [53] 100.0% ------------------------------------------------------------------------- ??? [44] 100.0% [49] 498(9.5%) 0(0.0%) bfsub ...-lib/math/private/bigfloat/mpfr.rkt:821:4 ??? [57] 66.3% new-mpfr [74] 33.7% ------------------------------------------------------------------------- mk-enode! [45] 100.0% [50] 466(8.8%) 0(0.0%) new-enode ...erbie/develop/src/core/enode.rkt:95:0 type-of-enode-expr [56] 100.0% ------------------------------------------------------------------------- for-loop [47] 100.0% [51] 296(5.6%) 0(0.0%) for-loop ...rbie/develop/src/core/egraph.rkt:224:8 hash-update! [58] 100.0% ------------------------------------------------------------------------- loop! [46] 100.0% [52] 154(2.9%) 0(0.0%) custom-set-union ...et/private/set-types.rkt:168:0 for-loop [59] 100.0% ------------------------------------------------------------------------- ??? [48] 100.0% [53] 150(2.8%) 0(0.0%) errors ...lies/herbie/develop/src/points.rkt:205:0 eval-prog [55] 100.0% ------------------------------------------------------------------------- loop! [46] 100.0% [54] 148(2.8%) 0(0.0%) update-en-expr ...evelop/src/core/egraph.rkt:212:0 for-loop [60] 100.0% ------------------------------------------------------------------------- errors [53] 13.0% eval-const-expr [10] 13.1% make-exacts* [21] 73.9% [55] 1158(22.0%) 0(0.0%) eval-prog ...herbie/develop/src/programs.rkt:109:0 hash-ref! [61] 59.1% temp68_2 [63] 26.1% loop [65] 14.9% ------------------------------------------------------------------------- new-enode [50] 100.0% [56] 466(8.8%) 0(0.0%) type-of-enode-expr ...elop/src/core/enode.rkt:68:0 ??? [62] 100.0% ------------------------------------------------------------------------- bfsub [49] 100.0% [57] 330(6.3%) 330(6.3%) ??? /home/p92/racket/collects/ffi/unsafe.rkt:898:4 ------------------------------------------------------------------------- for-loop [51] 100.0% [58] 296(5.6%) 0(0.0%) hash-update! ...cket/private/more-scheme.rkt:365:4 ??? [64] 100.0% ------------------------------------------------------------------------- custom-set-union [52] 100.0% [59] 154(2.9%) 0(0.0%) for-loop ...cts/racket/private/set-types.rkt:174:3 for-loop [66] 100.0% ------------------------------------------------------------------------- update-en-expr [54] 100.0% [60] 148(2.8%) 148(2.8%) for-loop ...rbie/develop/src/core/egraph.rkt:214:6 ------------------------------------------------------------------------- eval-prog [55] 33.0% loop [25] 67.0% [61] 684(13.0%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 ??? [2] 67.0% bigfloat-hash [67] 33.0% ------------------------------------------------------------------------- type-of-enode-expr [56] 100.0% [62] 466(8.8%) 0(0.0%) ??? ...ects/racket/contract/private/hash.rkt:227:4 contract-pos/neg-doubling/proc [68]100.0% ------------------------------------------------------------------------- eval-prog [55] 100.0% [63] 302(5.7%) 0(0.0%) temp68_2 (unknown source) compile16 [69] 100.0% ------------------------------------------------------------------------- hash-update! [58] 100.0% [64] 296(5.6%) 0(0.0%) ??? ...s/herbie/develop/src/core/egraph.rkt:226:24 for-loop [70] 100.0% ------------------------------------------------------------------------- eval-prog [55] 100.0% [65] 172(3.3%) 0(0.0%) loop (unknown source) expand-capturing-lifts [77] 100.0% ------------------------------------------------------------------------- for-loop [59] 100.0% [66] 154(2.9%) 154(2.9%) for-loop ...cts/racket/private/set-types.rkt:178:5 ------------------------------------------------------------------------- hash-ref! [61] 100.0% [67] 684(13.0%) 0(0.0%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:167:0 sig+exp->bigfloat [71] 100.0% ------------------------------------------------------------------------- ??? [62] 100.0% [68] 466(8.8%) 302(5.7%) contract-pos/neg-doubling/proc ...e/guts.rkt:956:0 ??? [73] 35.2% ------------------------------------------------------------------------- temp68_2 [63] 100.0% [69] 302(5.7%) 0(0.0%) compile16 (unknown source) temp74_0 [72] 100.0% ------------------------------------------------------------------------- ??? [64] 100.0% [70] 296(5.6%) 296(5.6%) for-loop ...bie/develop/src/core/egraph.rkt:227:26 ------------------------------------------------------------------------- bigfloat-hash [67] 100.0% [71] 684(13.0%) 0(0.0%) sig+exp->bigfloat ...ivate/bigfloat/mpfr.rkt:387:0 new-mpfr [74] 100.0% ------------------------------------------------------------------------- compile16 [69] 100.0% [72] 302(5.7%) 0(0.0%) temp74_0 (unknown source) expand-single [75] 100.0% ------------------------------------------------------------------------- contract-pos/neg-doubling/proc [68]100.0% [73] 164(3.1%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:191:2 ??? [76] 100.0% ------------------------------------------------------------------------- bfsub [49] 9.9% sig+exp->bigfloat [71] 40.1% bf [34] 50.0% [74] 1704(32.4%) 1704(32.4%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ------------------------------------------------------------------------- temp74_0 [72] 100.0% [75] 302(5.7%) 0(0.0%) expand-single (unknown source) expand-capturing-lifts [77] 100.0% ------------------------------------------------------------------------- ??? [73] 100.0% [76] 164(3.1%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:739:5 get/build-late-neg-projection [78] 100.0% ------------------------------------------------------------------------- loop [65] 36.3% expand-single [75] 63.7% [77] 474(9.0%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [79] 63.7% dispatch-transformer41 [80] 36.3% ------------------------------------------------------------------------- ??? [76] 100.0% [78] 164(3.1%) 0(0.0%) get/build-late-neg-projection ...te/guts.rkt:793:0 ??? [81] 100.0% ------------------------------------------------------------------------- expand-capturing-lifts [77] 100.0% [79] 302(5.7%) 0(0.0%) lambda-clause-expander (unknown source) finish-bodys [82] 100.0% ------------------------------------------------------------------------- expand-capturing-lifts [77] 100.0% [80] 172(3.3%) 0(0.0%) dispatch-transformer41 (unknown source) apply-transformer52 [83] 100.0% ------------------------------------------------------------------------- get/build-late-neg-projection [78] 100.0% [81] 164(3.1%) 0(0.0%) ??? ...cts/racket/contract/private/prop.rkt:344:12 build-compound-type-name [84] 100.0% ------------------------------------------------------------------------- for-loop [85] 33.6% lambda-clause-expander [79] 66.4% [82] 302(5.7%) 0(0.0%) finish-bodys (unknown source) for-loop [85] 100.0% ------------------------------------------------------------------------- dispatch-transformer41 [80] 100.0% [83] 172(3.3%) 0(0.0%) apply-transformer52 (unknown source) syntax-add-shifts44 [86] 100.0% ------------------------------------------------------------------------- ??? [81] 100.0% [84] 164(3.1%) 0(0.0%) build-compound-type-name ...private/guts.rkt:515:0 contract-struct-name [87] 100.0% ------------------------------------------------------------------------- for-loop [85] 20.1% finish-bodys [82] 79.9% [85] 302(5.7%) 302(5.7%) for-loop (unknown source) finish-bodys [82] 20.1% for-loop [85] 20.1% ------------------------------------------------------------------------- apply-transformer52 [83] 100.0% [86] 172(3.3%) 172(3.3%) syntax-add-shifts44 (unknown source) ------------------------------------------------------------------------- build-compound-type-name [84] 100.0% [87] 164(3.1%) 164(3.1%) contract-struct-name ...ract/private/prop.rkt:87:0 -------------------------------------------------------------------------