Profiling results ----------------- Total cpu time observed: 37720ms (out of 38316ms) Number of samples taken: 96 (once every 393ms) (Hiding functions with self<1.0% and local<2.0%: 2 of 80 hidden) =================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee =================================================================== run [5] 50.0% [1] 37720(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [3] 50.0% prepare-points [9] 24.9% loop [10] 20.1% run-improve39 [4] 5.0% ------------------------------------------------------------------- hash-ref! [28] 100.0% [2] 1884(5.0%) 0(0.0%) ??? ...erbie/clean-out-code/src/programs.rkt:153:5 loop [21] 100.0% ------------------------------------------------------------------- ??? [1] 100.0% [3] 37720(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [5] 100.0% ------------------------------------------------------------------- ??? [1] 100.0% [4] 3766(10.0%) 0(0.0%) run-improve39 ...n-out-code/src/mainloop.rkt:286:0 for-loop [6] 65.3% setup-prog!32 [7] 26.0% for-loop [8] 8.7% ------------------------------------------------------------------- profile-thunk16 [3] 100.0% [5] 37720(100.0%) 0(0.0%) run ...racket/share/pkgs/profile-lib/main.rkt:39:2 ??? [1] 100.0% ------------------------------------------------------------------- run-improve39 [4] 100.0% [6] 2460(6.5%) 0(0.0%) for-loop ...clean-out-code/src/mainloop.rkt:298:29 ??? [11] 100.0% ------------------------------------------------------------------- run-improve39 [4] 100.0% [7] 980(2.6%) 0(0.0%) setup-prog!32 ...an-out-code/src/mainloop.rkt:63:0 loop [10] 66.5% prepare-points [9] 33.5% ------------------------------------------------------------------- run-improve39 [4] 100.0% [8] 326(0.9%) 0(0.0%) for-loop .../clean-out-code/src/mainloop.rkt:303:8 run-iter! [12] 100.0% ------------------------------------------------------------------- setup-prog!32 [7] 1.7% ??? [1] 98.3% [9] 19104(50.6%) 0(0.0%) prepare-points ...an-out-code/src/points.rkt:156:0 loop [17] 56.5% make-exacts* [18] 22.6% loop [13] 19.3% for-loop [15] 1.6% ------------------------------------------------------------------- setup-prog!32 [7] 4.1% ??? [1] 95.9% [10] 15830(42.0%) 0(0.0%) loop ...herbie/clean-out-code/src/points.rkt:180:2 loop [13] 35.3% make-exacts* [18] 32.9% loop [17] 29.5% for-loop [15] 2.3% ------------------------------------------------------------------- for-loop [6] 100.0% [11] 2460(6.5%) 0(0.0%) ??? ...e/clean-out-code/src/core/simplify.rkt:31:0 iterate-egraph!7 [14] 100.0% ------------------------------------------------------------------- for-loop [8] 100.0% [12] 326(0.9%) 0(0.0%) run-iter! ...clean-out-code/src/mainloop.rkt:267:0 localize-error [16] 100.0% ------------------------------------------------------------------- prepare-points [9] 39.8% loop [10] 60.2% [13] 9286(24.6%) 0(0.0%) loop ...herbie/clean-out-code/src/points.rkt:124:2 make-exacts* [18] 63.4% loop [17] 36.6% ------------------------------------------------------------------- ??? [11] 100.0% [14] 2460(6.5%) 0(0.0%) iterate-egraph!7 ...ode/src/core/simplify.rkt:55:0 one-iter [19] 100.0% ------------------------------------------------------------------- prepare-points [9] 45.3% loop [10] 54.7% [15] 662(1.8%) 0(0.0%) for-loop ...e/clean-out-code/src/points.rkt:195:19 ??? [20] 100.0% ------------------------------------------------------------------- run-iter! [12] 100.0% [16] 326(0.9%) 0(0.0%) localize-error ...-code/src/core/localize.rkt:47:0 hash-ref! [28] 100.0% ------------------------------------------------------------------- loop [13] 18.0% loop [10] 24.8% prepare-points [9] 57.2% [17] 18858(50.0%) 0(0.0%) loop ...herbie/clean-out-code/src/points.rkt:107:4 loop [21] 96.6% ??? [27] 3.4% ------------------------------------------------------------------- prepare-points [9] 28.0% loop [10] 33.8% loop [13] 38.2% [18] 15414(40.9%) 0(0.0%) make-exacts* ...lean-out-code/src/points.rkt:104:0 loop [21] 81.7% eval-prog [22] 18.3% ------------------------------------------------------------------- iterate-egraph!7 [14] 100.0% [19] 2460(6.5%) 0(0.0%) one-iter ...an-out-code/src/core/simplify.rkt:66:0 for-loop [23] 87.4% for-loop [25] 12.6% ------------------------------------------------------------------- for-loop [15] 100.0% [20] 662(1.8%) 0(0.0%) ??? .../herbie/clean-out-code/src/points.rkt:172:7 for-loop [24] 54.7% ??? [26] 45.3% ------------------------------------------------------------------- loop! [53] 0.1% loop [17] 0.3% make-exacts* [18] 0.6% parse-loop14 [39] 1.0% ??? [2] 5.6% loop [21] 92.4% [21] 33668(89.3%) 0(0.0%) loop ...cket/collects/racket/private/map.rkt:40:19 loop [21] 92.4% hash-ref! [28] 6.6% ??? [27] 0.9% loop! [53] 0.1% ??? [52] 0.0% ------------------------------------------------------------------- make-exacts* [18] 100.0% [22] 2818(7.5%) 0(0.0%) eval-prog ...clean-out-code/src/programs.rkt:109:0 hash-ref! [28] 66.9% temp68_2 [30] 22.1% f206 [32] 11.1% ------------------------------------------------------------------- one-iter [19] 100.0% [23] 2150(5.7%) 0(0.0%) for-loop ...n-out-code/src/core/simplify.rkt:117:2 for-loop [29] 86.0% match-e [31] 14.0% ------------------------------------------------------------------- ??? [20] 100.0% [24] 362(1.0%) 0(0.0%) for-loop ...ie/clean-out-code/src/points.rkt:173:9 ??? [52] 100.0% ------------------------------------------------------------------- for-loop [25] 50.0% one-iter [19] 50.0% [25] 310(0.8%) 0(0.0%) for-loop ...n-out-code/src/core/simplify.rkt:76:12 for-loop [25] 50.0% match-e [31] 50.0% ------------------------------------------------------------------- ??? [20] 100.0% [26] 300(0.8%) 300(0.8%) ??? ...tract/private/arrow-higher-order.rkt:361:33 ------------------------------------------------------------------- loop [17] 2.3% loop [21] 97.7% [27] 27972(74.2%) 1342(3.6%) ??? ...erbie/clean-out-code/src/programs.rkt:129:2 ??? [33] 81.6% bf [34] 12.5% ------------------------------------------------------------------- localize-error [16] 7.4% eval-prog [22] 21.3% loop [21] 71.3% [28] 2210(5.9%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 ??? [2] 63.9% bigfloat-hash [35] 21.3% parse-loop14 [39] 14.8% ------------------------------------------------------------------- for-loop [23] 100.0% [29] 1850(4.9%) 0(0.0%) for-loop ...n-out-code/src/core/simplify.rkt:99:10 merge-egraph-nodes! [36] 34.7% loop! [53] 32.9% list-member? [66] 16.2% mk-enode! [41] 16.2% ------------------------------------------------------------------- eval-prog [22] 100.0% [30] 622(1.6%) 0(0.0%) temp68_2 (unknown source) compile16 [37] 100.0% ------------------------------------------------------------------- for-loop [50] 24.6% for-loop [23] 24.6% for-loop [25] 50.8% [31] 610(1.6%) 0(0.0%) match-e ...clean-out-code/src/core/ematch.rkt:46:0 for-loop [38] 75.4% ??? [40] 24.6% ------------------------------------------------------------------- f206 [32] 50.0% eval-prog [22] 50.0% [32] 312(0.8%) 312(0.8%) f206 .../collects/racket/match/compiler.rkt:507:40 f206 [32] 50.0% ------------------------------------------------------------------- ??? [27] 100.0% [33] 22812(60.5%) 3566(9.5%) ??? ...acket/collects/racket/private/kw.rkt:592:14 ??? [51] 56.9% bf* [42] 24.6% ??? [47] 1.4% ------------------------------------------------------------------- ??? [27] 100.0% [34] 3498(9.3%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [64] 81.0% ??? [65] 19.0% ------------------------------------------------------------------- hash-ref! [28] 100.0% [35] 1884(5.0%) 0(0.0%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:167:0 bfcanonicalize [43] 82.5% bigfloat->sig+exp [46] 17.5% ------------------------------------------------------------------- for-loop [29] 100.0% [36] 642(1.7%) 0(0.0%) merge-egraph-nodes! ...e/src/core/egraph.rkt:153:0 for-loop [44] 100.0% ------------------------------------------------------------------- temp68_2 [30] 100.0% [37] 622(1.6%) 0(0.0%) compile16 (unknown source) temp74_0 [45] 100.0% ------------------------------------------------------------------- match-e [31] 100.0% [38] 610(1.6%) 310(0.8%) for-loop ...ean-out-code/src/core/ematch.rkt:59:11 for-loop [50] 49.2% ------------------------------------------------------------------- hash-ref! [28] 100.0% [39] 326(0.9%) 0(0.0%) parse-loop14 ...s/racket/match/compiler.rkt:418:15 loop [21] 50.0% loop [48] 50.0% ------------------------------------------------------------------- match-e [31] 100.0% [40] 300(0.8%) 300(0.8%) ??? ...contract/private/arrow-val-first.rkt:388:18 ------------------------------------------------------------------- for-loop [29] 100.0% [41] 300(0.8%) 0(0.0%) mk-enode! ...an-out-code/src/core/egraph.rkt:102:0 new-enode [49] 100.0% ------------------------------------------------------------------- ??? [33] 100.0% [42] 5610(14.9%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [51] 100.0% ------------------------------------------------------------------- bigfloat-hash [35] 100.0% [43] 1554(4.1%) 1554(4.1%) bfcanonicalize .../private/bigfloat/mpfr.rkt:157:0 ------------------------------------------------------------------- merge-egraph-nodes! [36] 100.0% [44] 642(1.7%) 0(0.0%) for-loop ...an-out-code/src/core/egraph.rkt:201:10 loop! [53] 100.0% ------------------------------------------------------------------- compile16 [37] 100.0% [45] 622(1.6%) 0(0.0%) temp74_0 (unknown source) expand-single [54] 100.0% ------------------------------------------------------------------- bigfloat-hash [35] 100.0% [46] 330(0.9%) 330(0.9%) bigfloat->sig+exp ...ivate/bigfloat/mpfr.rkt:372:0 ------------------------------------------------------------------- ??? [33] 100.0% [47] 328(0.9%) 0(0.0%) ??? ...ects/racket/contract/private/prop.rkt:519:4 ??? [55] 100.0% ------------------------------------------------------------------- parse-loop14 [39] 2.5% loop [48] 97.5% [48] 326(0.9%) 0(0.0%) loop ...cket/collects/racket/private/map.rkt:54:19 loop [48] 97.5% ??? [52] 2.5% ------------------------------------------------------------------- mk-enode! [41] 100.0% [49] 300(0.8%) 0(0.0%) new-enode ...lean-out-code/src/core/enode.rkt:95:0 mutable-set [56] 100.0% ------------------------------------------------------------------- for-loop [38] 100.0% [50] 300(0.8%) 0(0.0%) for-loop ...ean-out-code/src/core/ematch.rkt:65:37 match-e [31] 100.0% ------------------------------------------------------------------- bf* [42] 30.2% ??? [33] 69.8% [51] 18584(49.3%) 5840(15.5%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfsub [57] 40.4% bfexp [58] 28.2% ------------------------------------------------------------------- loop [48] 7.8% for-loop [24] 8.7% loop [21] 83.5% [52] 4170(11.1%) 362(1.0%) ??? ...tract/private/arrow-higher-order.rkt:357:33 ->flonum [59] 91.3% ------------------------------------------------------------------- loop [21] 25.7% for-loop [44] 25.7% for-loop [29] 48.6% [53] 1250(3.3%) 308(0.8%) loop! ...e/clean-out-code/src/core/enode.rkt:190:2 custom-set-union [61] 48.6% loop [21] 25.7% set [62] 13.4% ------------------------------------------------------------------- temp74_0 [45] 100.0% [54] 622(1.6%) 0(0.0%) expand-single (unknown source) expand-capturing-lifts [60]100.0% ------------------------------------------------------------------- ??? [47] 100.0% [55] 328(0.9%) 328(0.9%) ??? ...lects/racket/contract/private/orc.rkt:83:14 ------------------------------------------------------------------- new-enode [49] 100.0% [56] 300(0.8%) 0(0.0%) mutable-set .../racket/private/set-types.rkt:999:0 for-loop [63] 100.0% ------------------------------------------------------------------- ??? [51] 100.0% [57] 7500(19.9%) 0(0.0%) bfsub ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [64] 76.7% ??? [65] 23.3% ------------------------------------------------------------------- ??? [51] 100.0% [58] 5244(13.9%) 0(0.0%) bfexp ...-lib/math/private/bigfloat/mpfr.rkt:618:4 new-mpfr [64] 87.5% ??? [65] 12.5% ------------------------------------------------------------------- ??? [52] 100.0% [59] 3808(10.1%) 3160(8.4%) ->flonum ...e/clean-out-code/src/programs.rkt:36:0 list-member? [66] 8.8% return/no-unsupplied [69] 8.2% ------------------------------------------------------------------- expand-single [54] 100.0% [60] 622(1.6%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [67]100.0% ------------------------------------------------------------------- loop! [53] 100.0% [61] 608(1.6%) 0(0.0%) custom-set-union ...et/private/set-types.rkt:168:0 for-loop [68] 100.0% ------------------------------------------------------------------- loop! [53] 100.0% [62] 334(0.9%) 334(0.9%) set ...collects/racket/private/set-types.rkt:981:0 ------------------------------------------------------------------- mutable-set [56] 100.0% [63] 300(0.8%) 300(0.8%) for-loop ...cts/racket/private/set-types.rkt:943:2 ------------------------------------------------------------------- bf [34] 21.5% bfexp [58] 34.8% bfsub [57] 43.7% [64] 13170(34.9%) 13170(34.9%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ------------------------------------------------------------------- bfexp [58] 21.4% bf [34] 21.7% bfsub [57] 57.0% [65] 3072(8.1%) 3072(8.1%) ??? /home/p92/racket/collects/ffi/unsafe.rkt:898:4 ------------------------------------------------------------------- for-loop [29] 47.2% ->flonum [59] 52.8% [66] 636(1.7%) 336(0.9%) list-member? ...llects/racket/private/set.rkt:24:0 member [72] 47.2% ------------------------------------------------------------------- expand-capturing-lifts [60]100.0% [67] 622(1.6%) 0(0.0%) lambda-clause-expander (unknown source) finish-bodys [70] 100.0% ------------------------------------------------------------------- custom-set-union [61] 100.0% [68] 608(1.6%) 300(0.8%) for-loop ...cts/racket/private/set-types.rkt:174:3 for-loop [71] 50.7% ------------------------------------------------------------------- ->flonum [59] 100.0% [69] 312(0.8%) 312(0.8%) return/no-unsupplied ...ct/private/arr-i.rkt:534:0 ------------------------------------------------------------------- lambda-clause-expander [67] 29.9% for-loop [73] 70.1% [70] 622(1.6%) 0(0.0%) finish-bodys (unknown source) for-loop [73] 100.0% ------------------------------------------------------------------- for-loop [68] 100.0% [71] 308(0.8%) 308(0.8%) for-loop ...cts/racket/private/set-types.rkt:178:5 ------------------------------------------------------------------- list-member? [66] 100.0% [72] 300(0.8%) 300(0.8%) member (unknown source) ------------------------------------------------------------------- for-loop [73] 21.3% finish-bodys [70] 78.7% [73] 622(1.6%) 0(0.0%) for-loop (unknown source) finish-bodys [70] 57.5% for-loop [73] 21.3% syntax-e [74] 21.3% ------------------------------------------------------------------- for-loop [73] 100.0% [74] 622(1.6%) 0(0.0%) syntax-e (unknown source) syntax-e/no-taint [75] 100.0% ------------------------------------------------------------------- syntax-e [74] 100.0% [75] 622(1.6%) 0(0.0%) syntax-e/no-taint (unknown source) loop [76] 100.0% ------------------------------------------------------------------- syntax-e/no-taint [75] 100.0% [76] 622(1.6%) 0(0.0%) loop (unknown source) gf [77] 100.0% ------------------------------------------------------------------- loop [76] 100.0% [77] 622(1.6%) 0(0.0%) gf (unknown source) propagation-apply [78] 100.0% ------------------------------------------------------------------- gf [77] 100.0% [78] 622(1.6%) 622(1.6%) propagation-apply (unknown source) -------------------------------------------------------------------