Profiling results ----------------- Total cpu time observed: 13020ms (out of 13496ms) Number of samples taken: 36 (once every 362ms) ====================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ====================================================================== run [9] 48.8% [1] 13020(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [3] 48.8% prepare-points [4] 25.4% loop [6] 13.3% run-improve48 [7] 11.2% ??? [32] 1.4% ---------------------------------------------------------------------- for-loop [73] 8.1% loop [46] 91.9% [2] 7680(59.0%) 702(5.4%) ??? ...erbie/clean-out-code/src/programs.rkt:129:2 ??? [5] 66.6% ??? [8] 24.2% ---------------------------------------------------------------------- ??? [1] 100.0% [3] 12706(97.6%) 0(0.0%) profile-thunk16 ...t/pkgs/profile-lib/main.rkt:9:0 run [9] 100.0% ---------------------------------------------------------------------- ??? [1] 100.0% [4] 6602(50.7%) 0(0.0%) prepare-points ...an-out-code/src/points.rkt:156:0 loop [18] 51.5% make-exacts* [19] 27.7% loop [11] 15.5% for-loop [16] 5.2% ---------------------------------------------------------------------- ??? [2] 100.0% [5] 5116(39.3%) 1450(11.1%) ??? ...acket/collects/racket/private/kw.rkt:444:14 bf* [10] 58.4% ...higher-order.rkt:346:33 [15] 7.1% ??? [17] 6.1% ---------------------------------------------------------------------- ??? [1] 100.0% [6] 3464(26.6%) 0(0.0%) loop ...herbie/clean-out-code/src/points.rkt:180:2 loop [11] 45.1% loop [18] 34.1% make-exacts* [19] 20.8% ---------------------------------------------------------------------- ??? [1] 100.0% [7] 2594(19.9%) 0(0.0%) run-improve48 ...n-out-code/src/mainloop.rkt:286:0 for-loop [12] 64.5% for-loop [14] 35.5% ---------------------------------------------------------------------- ??? [2] 100.0% [8] 1862(14.3%) 392(3.0%) ??? ...contract/private/../../private/kw.rkt:761:9 real->bigfloat [13] 78.9% ---------------------------------------------------------------------- profile-thunk16 [3] 100.0% [9] 12706(97.6%) 0(0.0%) run ...share/racket/pkgs/profile-lib/main.rkt:39:2 ??? [1] 100.0% ---------------------------------------------------------------------- ??? [5] 100.0% [10] 2990(23.0%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [20] 100.0% ---------------------------------------------------------------------- prepare-points [4] 39.6% loop [6] 60.4% [11] 2588(19.9%) 0(0.0%) loop ...herbie/clean-out-code/src/points.rkt:124:2 make-exacts* [19] 54.4% loop [18] 45.6% ---------------------------------------------------------------------- run-improve48 [7] 100.0% [12] 1674(12.9%) 0(0.0%) for-loop .../clean-out-code/src/mainloop.rkt:303:8 run-iter! [21] 100.0% ---------------------------------------------------------------------- ??? [8] 100.0% [13] 1470(11.3%) 430(3.3%) real->bigfloat .../private/bigfloat/mpfr.rkt:451:0 ??? [27] 70.7% ---------------------------------------------------------------------- run-improve48 [7] 100.0% [14] 920(7.1%) 0(0.0%) for-loop ...clean-out-code/src/mainloop.rkt:298:29 ??? [34] 100.0% ---------------------------------------------------------------------- ??? [5] 100.0% [15] 362(2.8%) 0(0.0%) ...higher-order.rkt:346:33 (unknown source) ??? [35] 100.0% ---------------------------------------------------------------------- prepare-points [4] 100.0% [16] 344(2.6%) 0(0.0%) for-loop ...e/clean-out-code/src/points.rkt:195:19 ??? [22] 100.0% ---------------------------------------------------------------------- ??? [5] 100.0% [17] 314(2.4%) 314(2.4%) ??? ...rivate/unconstrained-domain-arrow.rkt:40:13 ---------------------------------------------------------------------- loop [11] 20.5% loop [6] 20.5% prepare-points [4] 59.0% [18] 5762(44.3%) 0(0.0%) loop ...herbie/clean-out-code/src/points.rkt:107:4 loop [46] 100.0% ---------------------------------------------------------------------- loop [6] 18.2% loop [11] 35.6% prepare-points [4] 46.3% [19] 3960(30.4%) 0(0.0%) make-exacts* ...lean-out-code/src/points.rkt:104:0 loop [46] 100.0% ---------------------------------------------------------------------- bf* [10] 100.0% [20] 2990(23.0%) 0(0.0%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [23] 100.0% ---------------------------------------------------------------------- for-loop [12] 100.0% [21] 1674(12.9%) 0(0.0%) run-iter! ...clean-out-code/src/mainloop.rkt:267:0 simplify! [24] 42.2% finalize-iter! [25] 37.3% gen-rewrites! [26] 20.5% ---------------------------------------------------------------------- for-loop [16] 100.0% [22] 344(2.6%) 0(0.0%) ??? .../herbie/clean-out-code/src/points.rkt:172:7 ??? [32] 100.0% ---------------------------------------------------------------------- ??? [20] 100.0% [23] 2990(23.0%) 810(6.2%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:819:4 ??? [27] 72.9% ---------------------------------------------------------------------- run-iter! [21] 100.0% [24] 706(5.4%) 0(0.0%) simplify! ...clean-out-code/src/mainloop.rkt:181:0 for-loop [28] 100.0% ---------------------------------------------------------------------- run-iter! [21] 100.0% [25] 624(4.8%) 0(0.0%) finalize-iter! ...-out-code/src/mainloop.rkt:221:0 ??? [63] 100.0% ---------------------------------------------------------------------- run-iter! [21] 100.0% [26] 344(2.6%) 0(0.0%) gen-rewrites! ...n-out-code/src/mainloop.rkt:164:0 for-loop [29] 100.0% ---------------------------------------------------------------------- real->bigfloat [13] 32.3% bfmul [23] 67.7% [27] 3220(24.7%) 3220(24.7%) ??? .../share/racket/collects/ffi/unsafe.rkt:861:4 ---------------------------------------------------------------------- simplify! [24] 100.0% [28] 706(5.4%) 0(0.0%) for-loop .../clean-out-code/src/mainloop.rkt:185:6 for-loop [30] 100.0% ---------------------------------------------------------------------- gen-rewrites! [26] 100.0% [29] 344(2.6%) 0(0.0%) for-loop ...clean-out-code/src/mainloop.rkt:169:11 ??? [31] 100.0% ---------------------------------------------------------------------- for-loop [28] 100.0% [30] 706(5.4%) 0(0.0%) for-loop .../clean-out-code/src/mainloop.rkt:209:8 ??? [32] 100.0% ---------------------------------------------------------------------- for-loop [29] 100.0% [31] 344(2.6%) 0(0.0%) ??? ...bie/clean-out-code/src/alternative.rkt:60:0 rewriter [33] 100.0% ---------------------------------------------------------------------- ??? [22] 9.4% ??? [34] 9.6% for-loop [30] 9.6% ??? [1] 9.8% loop [46] 61.5% [32] 3660(28.1%) 344(2.6%) ??? ...tract/private/arrow-higher-order.rkt:342:33 ??? [34] 80.8% ??? [35] 9.8% ---------------------------------------------------------------------- matcher [42] 50.0% ??? [31] 50.0% [33] 344(2.6%) 344(2.6%) rewriter ...an-out-code/src/core/matcher.rkt:131:2 for-loop [36] 50.0% ---------------------------------------------------------------------- ??? [34] 6.1% for-loop [14] 23.7% ??? [32] 70.2% [34] 3876(29.8%) 688(5.3%) ??? ...llects/racket/private/norm-define.rkt:53:83 iterate-egraph!7 [37] 29.8% ??? [38] 19.4% ??? [39] 10.7% return/no-unsupplied [40] 10.2% ??? [34] 6.1% ??? [32] 6.1% ---------------------------------------------------------------------- for-loop [41] 24.9% ??? [32] 24.9% ...higher-order.rkt:346:33 [15] 50.1% [35] 722(5.5%) 722(5.5%) ??? ...cts/racket/contract/private/list.rkt:187:10 for-loop [41] 24.9% ---------------------------------------------------------------------- rewriter [33] 100.0% [36] 344(2.6%) 0(0.0%) for-loop ...n-out-code/src/core/matcher.rkt:134:10 matcher [42] 100.0% ---------------------------------------------------------------------- ??? [34] 100.0% [37] 1626(12.5%) 0(0.0%) iterate-egraph!7 ...ode/src/core/simplify.rkt:55:0 one-iter [43] 59.9% map-enodes [44] 40.1% ---------------------------------------------------------------------- ??? [34] 100.0% [38] 752(5.8%) 320(2.5%) ??? ...racket/collects/racket/private/kw.rkt:761:9 ??? [45] 57.4% ---------------------------------------------------------------------- ??? [34] 100.0% [39] 414(3.2%) 414(3.2%) ??? ...-racket/utils/simple-result-arrow.rkt:60:15 ---------------------------------------------------------------------- ??? [34] 100.0% [40] 396(3.0%) 396(3.0%) return/no-unsupplied ...ct/private/arr-i.rkt:533:0 ---------------------------------------------------------------------- ??? [35] 100.0% [41] 360(2.8%) 0(0.0%) for-loop ...acket/contract/private/list.rkt:190:15 ??? [35] 100.0% ---------------------------------------------------------------------- matcher [42] 50.0% for-loop [36] 50.0% [42] 344(2.6%) 0(0.0%) matcher ...ean-out-code/src/core/matcher.rkt:167:2 matcher [42] 50.0% rewriter [33] 50.0% ---------------------------------------------------------------------- iterate-egraph!7 [37] 100.0% [43] 974(7.5%) 0(0.0%) one-iter ...an-out-code/src/core/simplify.rkt:66:0 for-loop [47] 68.8% for-loop [48] 31.2% ---------------------------------------------------------------------- iterate-egraph!7 [37] 100.0% [44] 652(5.0%) 0(0.0%) map-enodes ...n-out-code/src/core/egraph.rkt:140:0 for-loop [50] 52.5% loop [46] 47.5% ---------------------------------------------------------------------- ??? [38] 100.0% [45] 432(3.3%) 432(3.3%) ??? ...s/racket/contract/private/arr-i.rkt:1233:27 ---------------------------------------------------------------------- map-enodes [44] 0.1% loop [18] 0.2% make-exacts* [19] 0.7% loop [46] 99.0% [46] 10032(77.1%) 416(3.2%) loop ...cket/collects/racket/private/map.rkt:25:19 loop [46] 99.0% ??? [2] 0.7% ??? [32] 0.2% for-loop [50] 0.1% ---------------------------------------------------------------------- one-iter [43] 100.0% [47] 670(5.1%) 0(0.0%) for-loop ...n-out-code/src/core/simplify.rkt:116:2 for-loop [49] 100.0% ---------------------------------------------------------------------- for-loop [48] 50.0% one-iter [43] 50.0% [48] 304(2.3%) 0(0.0%) for-loop ...n-out-code/src/core/simplify.rkt:76:12 for-loop [48] 50.0% match-e [51] 50.0% ---------------------------------------------------------------------- for-loop [47] 100.0% [49] 670(5.1%) 0(0.0%) for-loop ...n-out-code/src/core/simplify.rkt:98:10 substitute-e [52] 54.3% mk-enode! [56] 45.7% ---------------------------------------------------------------------- loop [46] 47.5% map-enodes [44] 52.5% [50] 652(5.0%) 310(2.4%) for-loop ...n-out-code/src/core/simplify.rkt:145:2 eval-const-expr [53] 52.5% ---------------------------------------------------------------------- for-loop [48] 33.3% for-loop [58] 66.7% [51] 304(2.3%) 0(0.0%) match-e ...clean-out-code/src/core/ematch.rkt:46:0 for-loop [58] 33.3% for-loop [55] 33.3% variable? [54] 33.3% ---------------------------------------------------------------------- for-loop [49] 100.0% [52] 364(2.8%) 0(0.0%) substitute-e ...-out-code/src/core/ematch.rkt:71:0 mk-enode! [56] 100.0% ---------------------------------------------------------------------- for-loop [50] 100.0% [53] 342(2.6%) 0(0.0%) eval-const-expr ...out-code/src/programs.rkt:132:0 eval-prog [57] 100.0% ---------------------------------------------------------------------- match-e [51] 100.0% [54] 304(2.3%) 0(0.0%) variable? ...-out-code/src/syntax/syntax.rkt:676:0 ??? [63] 100.0% ---------------------------------------------------------------------- match-e [51] 100.0% [55] 304(2.3%) 0(0.0%) for-loop ...ean-out-code/src/core/ematch.rkt:59:11 for-loop [58] 100.0% ---------------------------------------------------------------------- for-loop [49] 45.7% substitute-e [52] 54.3% [56] 670(5.1%) 0(0.0%) mk-enode! ...an-out-code/src/core/egraph.rkt:102:0 new-enode [59] 100.0% ---------------------------------------------------------------------- eval-const-expr [53] 100.0% [57] 342(2.6%) 0(0.0%) eval-prog ...clean-out-code/src/programs.rkt:109:0 new-lambda [60] 100.0% ---------------------------------------------------------------------- for-loop [55] 50.0% match-e [51] 50.0% [58] 304(2.3%) 0(0.0%) for-loop ...ean-out-code/src/core/ematch.rkt:65:37 match-e [51] 100.0% ---------------------------------------------------------------------- mk-enode! [56] 100.0% [59] 670(5.1%) 0(0.0%) new-enode ...lean-out-code/src/core/enode.rkt:97:0 type-of-enode-expr [61] 100.0% ---------------------------------------------------------------------- eval-prog [57] 100.0% [60] 342(2.6%) 342(2.6%) new-lambda ...ollects/racket/private/kw.rkt:679:11 ---------------------------------------------------------------------- new-enode [59] 100.0% [61] 670(5.1%) 0(0.0%) type-of-enode-expr ...code/src/core/enode.rkt:68:0 get-sigs [62] 100.0% ---------------------------------------------------------------------- type-of-enode-expr [61] 100.0% [62] 670(5.1%) 0(0.0%) get-sigs .../clean-out-code/src/type-check.rkt:5:0 ??? [63] 54.3% ??? [64] 45.7% ---------------------------------------------------------------------- variable? [54] 23.5% get-sigs [62] 28.2% finalize-iter! [25] 48.3% [63] 1292(9.9%) 304(2.3%) ??? ...contract/private/arrow-val-first.rkt:357:18 for-loop [65] 48.3% hash-has-key? [66] 28.2% ---------------------------------------------------------------------- get-sigs [62] 100.0% [64] 306(2.4%) 0(0.0%) ??? ...ts/racket/contract/private/arr-i.rkt:804:10 for-loop [67] 100.0% ---------------------------------------------------------------------- ??? [63] 100.0% [65] 624(4.8%) 0(0.0%) for-loop ...n-out-code/src/core/alt-table.rkt:51:2 atab-add-altn [68] 100.0% ---------------------------------------------------------------------- ??? [63] 100.0% [66] 364(2.8%) 364(2.8%) hash-has-key? ...et/private/more-scheme.rkt:368:13 ---------------------------------------------------------------------- ??? [64] 100.0% [67] 306(2.4%) 0(0.0%) for-loop ...racket/contract/private/hash.rkt:239:6 ??? [69] 100.0% ---------------------------------------------------------------------- for-loop [65] 100.0% [68] 624(4.8%) 0(0.0%) atab-add-altn ...code/src/core/alt-table.rkt:208:0 best-and-tied-at-points [70] 100.0% ---------------------------------------------------------------------- for-loop [67] 100.0% [69] 306(2.4%) 0(0.0%) ??? ...ects/racket/contract/private/prop.rkt:493:4 ??? [71] 100.0% ---------------------------------------------------------------------- atab-add-altn [68] 100.0% [70] 624(4.8%) 0(0.0%) best-and-tied-at-points ...ore/alt-table.rkt:122:0 errors [72] 100.0% ---------------------------------------------------------------------- ??? [69] 100.0% [71] 306(2.4%) 306(2.4%) ??? ...lects/racket/contract/private/orc.rkt:83:14 ---------------------------------------------------------------------- best-and-tied-at-points [70] 100.0% [72] 624(4.8%) 0(0.0%) errors ...rbie/clean-out-code/src/points.rkt:203:0 for-loop [73] 100.0% ---------------------------------------------------------------------- errors [72] 100.0% [73] 624(4.8%) 0(0.0%) for-loop ...ie/clean-out-code/src/points.rkt:206:4 ??? [2] 100.0% ----------------------------------------------------------------------