Profiling results ----------------- Total cpu time observed: 5150ms (out of 5392ms) Number of samples taken: 27 (once every 191ms) =============================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee =============================================================== ??? [14] 100.0% [1] 4678(90.8%) 0(0.0%) profile-thunk16 ...t/pkgs/profile-lib/main.rkt:9:0 run [9] 100.0% --------------------------------------------------------------- ??? [14] 100.0% [2] 2088(40.5%) 0(0.0%) run-improve50 ...n-out-code/src/mainloop.rkt:234:0 setup-alt-simplified [11] 92.0% prepare-points [19] 8.0% --------------------------------------------------------------- ??? [28] 100.0% [3] 2084(40.5%) 0(0.0%) iterate-egraph!13 ...e/src/core/simplify.rkt:105:0 one-iter [10] 100.0% --------------------------------------------------------------- f137 [42] 19.3% loop [34] 80.7% [4] 848(16.5%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:342:33 ??? [28] 80.7% ??? [48] 19.3% --------------------------------------------------------------- ??? [14] 100.0% [5] 808(15.7%) 0(0.0%) loop ...herbie/clean-out-code/src/points.rkt:238:2 loop [23] 78.5% loop [30] 21.5% --------------------------------------------------------------- match-e [20] 100.0% [6] 644(12.5%) 0(0.0%) for-loop ...ean-out-code/src/core/ematch.rkt:59:11 for-loop [12] 86.2% loop [34] 13.8% --------------------------------------------------------------- hash-ref! [44] 100.0% [7] 158(3.1%) 0(0.0%) ??? ...erbie/clean-out-code/src/programs.rkt:194:5 loop [34] 100.0% --------------------------------------------------------------- ??? [28] 100.0% [8] 152(3.0%) 0(0.0%) loop ...clean-out-code/src/core/simplify.rkt:237:2 pass [13] 100.0% --------------------------------------------------------------- profile-thunk16 [1] 96.8% [9] 4834(93.9%) 0(0.0%) run ...share/racket/pkgs/profile-lib/main.rkt:39:2 ??? [14] 100.0% --------------------------------------------------------------- iterate-egraph!13 [3] 100.0% [10] 2084(40.5%) 0(0.0%) one-iter ...n-out-code/src/core/simplify.rkt:116:0 for-loop [16] 70.5% for-loop [17] 29.5% --------------------------------------------------------------- run-improve50 [2] 100.0% [11] 1920(37.3%) 0(0.0%) setup-alt-simplified ...out-code/src/glue.rkt:49:0 simplify-alt [15] 100.0% --------------------------------------------------------------- for-loop [6] 100.0% [12] 644(12.5%) 0(0.0%) for-loop ...ean-out-code/src/core/ematch.rkt:65:37 match-e [20] 100.0% --------------------------------------------------------------- loop [8] 100.0% [13] 152(3.0%) 0(0.0%) pass ...clean-out-code/src/core/simplify.rkt:227:2 for-loop [18] 100.0% --------------------------------------------------------------- run [9] 51.6% [14] 4834(93.9%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 48.4% prepare-points [19] 21.7% run-improve50 [2] 21.6% loop [5] 8.4% --------------------------------------------------------------- setup-alt-simplified [11] 100.0% [15] 1920(37.3%) 0(0.0%) simplify-alt ...e/clean-out-code/src/glue.rkt:76:0 ??? [28] 100.0% --------------------------------------------------------------- one-iter [10] 100.0% [16] 1470(28.5%) 0(0.0%) for-loop ...n-out-code/src/core/simplify.rkt:166:2 for-loop [21] 56.9% match-e [20] 43.1% --------------------------------------------------------------- for-loop [17] 50.0% one-iter [10] 50.0% [17] 614(11.9%) 0(0.0%) for-loop ...-out-code/src/core/simplify.rkt:126:12 for-loop [17] 50.0% match-e [20] 50.0% --------------------------------------------------------------- pass [13] 100.0% [18] 152(3.0%) 0(0.0%) for-loop ...-out-code/src/core/simplify.rkt:230:29 argmin [22] 100.0% --------------------------------------------------------------- run-improve50 [2] 8.0% ??? [14] 92.0% [19] 2106(40.9%) 0(0.0%) prepare-points ...an-out-code/src/points.rkt:214:0 make-exacts* [29] 41.3% loop [23] 26.1% loop [30] 25.2% for-loop [26] 7.4% --------------------------------------------------------------- for-loop [12] 25.8% for-loop [17] 30.5% for-loop [16] 43.7% [20] 1248(24.2%) 604(11.7%) match-e ...clean-out-code/src/core/ematch.rkt:46:0 for-loop [6] 32.9% ??? [25] 18.7% --------------------------------------------------------------- for-loop [16] 100.0% [21] 836(16.2%) 0(0.0%) for-loop ...-out-code/src/core/simplify.rkt:148:10 merge-egraph-nodes! [24] 100.0% --------------------------------------------------------------- for-loop [18] 100.0% [22] 152(3.0%) 0(0.0%) argmin ...re/racket/collects/racket/list.rkt:743:0 for-loop [27] 100.0% --------------------------------------------------------------- prepare-points [19] 46.5% loop [5] 53.5% [23] 1184(23.0%) 0(0.0%) loop ...herbie/clean-out-code/src/points.rkt:158:2 make-exacts* [29] 59.0% loop [30] 41.0% --------------------------------------------------------------- merge-egraph-nodes! [24] 9.8% for-loop [21] 90.2% [24] 836(16.2%) 0(0.0%) merge-egraph-nodes! ...e/src/core/egraph.rkt:153:0 update-leader! [31] 80.4% merge-egraph-nodes! [24] 9.8% loop! [43] 9.8% --------------------------------------------------------------- match-e [20] 100.0% [25] 466(9.0%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:357:18 hash-has-key? [32] 100.0% --------------------------------------------------------------- prepare-points [19] 100.0% [26] 156(3.0%) 0(0.0%) for-loop ...e/clean-out-code/src/points.rkt:255:26 ??? [33] 100.0% --------------------------------------------------------------- argmin [22] 100.0% [27] 152(3.0%) 0(0.0%) for-loop .../clean-out-code/src/programs.rkt:210:2 ??? [28] 100.0% --------------------------------------------------------------- for-loop [27] 1.7% ??? [4] 23.4% simplify-alt [15] 32.9% ??? [28] 37.4% [28] 2920(56.7%) 520(10.1%) ??? ...llects/racket/private/norm-define.rkt:53:83 ??? [28] 37.4% iterate-egraph!13 [3] 35.7% return/no-unsupplied [37] 5.6% loop [8] 1.7% ??? [39] 1.7% --------------------------------------------------------------- loop [23] 44.5% prepare-points [19] 55.5% [29] 1568(30.4%) 0(0.0%) make-exacts* ...lean-out-code/src/points.rkt:138:0 loop [34] 79.5% eval-prog [36] 20.5% --------------------------------------------------------------- loop [5] 14.6% loop [23] 40.8% prepare-points [19] 44.5% [30] 1190(23.1%) 0(0.0%) loop ...herbie/clean-out-code/src/points.rkt:141:4 loop [34] 100.0% --------------------------------------------------------------- merge-egraph-nodes! [24] 100.0% [31] 672(13.0%) 178(3.5%) update-leader! ...t-code/src/core/egraph.rkt:218:0 for-loop [35] 73.5% --------------------------------------------------------------- ??? [25] 100.0% [32] 466(9.0%) 466(9.0%) hash-has-key? ...et/private/more-scheme.rkt:368:13 --------------------------------------------------------------- for-loop [26] 100.0% [33] 156(3.0%) 0(0.0%) ??? .../herbie/clean-out-code/src/points.rkt:230:7 ??? [38] 100.0% --------------------------------------------------------------- loop! [43] 0.2% make-exacts* [29] 0.6% loop [30] 2.6% ??? [7] 5.4% for-loop [6] 6.1% loop [34] 85.1% [34] 2936(57.0%) 178(3.5%) loop ...cket/collects/racket/private/map.rkt:25:19 loop [34] 85.1% hash-ref! [44] 5.4% ??? [40] 3.2% loop! [43] 0.2% ??? [4] 0.1% --------------------------------------------------------------- update-leader! [31] 100.0% [35] 494(9.6%) 0(0.0%) for-loop ...ean-out-code/src/core/egraph.rkt:223:6 for-loop [41] 100.0% --------------------------------------------------------------- make-exacts* [29] 100.0% [36] 322(6.3%) 0(0.0%) eval-prog ...clean-out-code/src/programs.rkt:150:0 f137 [42] 50.9% hash-ref! [44] 49.1% --------------------------------------------------------------- ??? [28] 100.0% [37] 164(3.2%) 164(3.2%) return/no-unsupplied ...ct/private/arr-i.rkt:533:0 --------------------------------------------------------------- ??? [33] 100.0% [38] 156(3.0%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:346:33 ??? [45] 100.0% --------------------------------------------------------------- ??? [28] 100.0% [39] 152(3.0%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:737:4 ??? [46] 100.0% --------------------------------------------------------------- loop [34] 100.0% [40] 1752(34.0%) 0(0.0%) ??? ...erbie/clean-out-code/src/programs.rkt:156:4 ??? [47] 80.5% ??? [48] 19.5% --------------------------------------------------------------- for-loop [35] 100.0% [41] 494(9.6%) 0(0.0%) for-loop ...ean-out-code/src/core/egraph.rkt:224:8 hash-update! [49] 100.0% --------------------------------------------------------------- eval-prog [36] 33.3% f137 [42] 66.7% [42] 164(3.2%) 0(0.0%) f137 .../collects/racket/match/compiler.rkt:507:40 f137 [42] 66.7% ??? [4] 33.3% --------------------------------------------------------------- loop [34] 50.0% merge-egraph-nodes! [24] 50.0% [43] 164(3.2%) 164(3.2%) loop! ...e/clean-out-code/src/core/enode.rkt:192:2 loop [34] 50.0% --------------------------------------------------------------- loop [34] 50.0% eval-prog [36] 50.0% [44] 158(3.1%) 0(0.0%) hash-ref! ...racket/private/more-scheme.rkt:373:13 ??? [7] 50.0% bigfloat-hash [50] 50.0% --------------------------------------------------------------- ??? [38] 100.0% [45] 156(3.0%) 0(0.0%) ??? ...ects/racket/contract/private/prop.rkt:493:4 ??? [51] 100.0% --------------------------------------------------------------- ??? [39] 100.0% [46] 152(3.0%) 0(0.0%) ??? ...private/unconstrained-domain-arrow.rkt:74:4 make-keyword-procedure [52]100.0% --------------------------------------------------------------- ??? [40] 100.0% [47] 1410(27.4%) 536(10.4%) ??? ...acket/collects/racket/private/kw.rkt:444:14 bf* [53] 38.6% ??? [59] 23.4% --------------------------------------------------------------- ??? [4] 32.4% ??? [40] 67.6% [48] 506(9.8%) 0(0.0%) ??? ...contract/private/../../private/kw.rkt:761:9 real->bigfloat [55] 67.6% integer->bigfloat [56] 32.4% --------------------------------------------------------------- for-loop [41] 100.0% [49] 494(9.6%) 0(0.0%) hash-update! ...ket/private/more-scheme.rkt:362:13 ??? [54] 100.0% --------------------------------------------------------------- hash-ref! [44] 100.0% [50] 158(3.1%) 0(0.0%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:165:0 sig+exp->bigfloat [57] 100.0% --------------------------------------------------------------- ??? [45] 100.0% [51] 156(3.0%) 0(0.0%) ??? ...lects/racket/contract/private/orc.rkt:83:14 ??? [58] 100.0% --------------------------------------------------------------- ??? [46] 100.0% [52] 152(3.0%) 152(3.0%) make-keyword-procedure ...ket/private/kw.rkt:260:4 --------------------------------------------------------------- ??? [47] 100.0% [53] 544(10.6%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [59] 100.0% --------------------------------------------------------------- hash-update! [49] 100.0% [54] 494(9.6%) 0(0.0%) ??? ...e/clean-out-code/src/core/egraph.rkt:226:24 for-loop [60] 100.0% --------------------------------------------------------------- ??? [48] 100.0% [55] 342(6.6%) 0(0.0%) real->bigfloat .../private/bigfloat/mpfr.rkt:451:0 ??? [62] 100.0% --------------------------------------------------------------- ??? [48] 100.0% [56] 164(3.2%) 164(3.2%) integer->bigfloat ...ivate/bigfloat/mpfr.rkt:399:0 --------------------------------------------------------------- bigfloat-hash [50] 100.0% [57] 158(3.1%) 158(3.1%) sig+exp->bigfloat ...ivate/bigfloat/mpfr.rkt:385:0 --------------------------------------------------------------- ??? [51] 100.0% [58] 156(3.0%) 156(3.0%) ??? ...ects/racket/contract/private/list.rkt:141:5 --------------------------------------------------------------- ??? [47] 37.8% bf* [53] 62.2% [59] 874(17.0%) 0(0.0%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfadd [61] 100.0% --------------------------------------------------------------- ??? [54] 100.0% [60] 494(9.6%) 494(9.6%) for-loop ...an-out-code/src/core/egraph.rkt:227:26 --------------------------------------------------------------- ??? [59] 100.0% [61] 874(17.0%) 698(13.6%) bfadd ...-lib/math/private/bigfloat/mpfr.rkt:819:4 ??? [62] 20.1% --------------------------------------------------------------- bfadd [61] 34.0% real->bigfloat [55] 66.0% [62] 518(10.1%) 518(10.1%) ??? .../share/racket/collects/ffi/unsafe.rkt:861:4 ---------------------------------------------------------------