Profiling results ----------------- Total cpu time observed: 8560ms (out of 8752ms) Number of samples taken: 42 (once every 204ms) ============================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ============================================================================== [1] 8560(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [4] 100.0% ------------------------------------------------------------------------------ for-loop [37] 47.1% loop [61] 52.9% [2] 420(4.9%) 0(0.0%) syntax-e (unknown source) syntax-e/no-taint [5] 100.0% ------------------------------------------------------------------------------ for-loop [37] 100.0% [3] 222(2.6%) 0(0.0%) dispatch-transformer41 (unknown source) apply-transformer52 [6] 100.0% ------------------------------------------------------------------------------ ??? [1] 100.0% [4] 8560(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [7] 100.0% ------------------------------------------------------------------------------ syntax-e [2] 100.0% [5] 420(4.9%) 0(0.0%) syntax-e/no-taint (unknown source) loop [61] 100.0% ------------------------------------------------------------------------------ dispatch-transformer41 [3] 100.0% [6] 222(2.6%) 0(0.0%) apply-transformer52 (unknown source) loop [61] 100.0% ------------------------------------------------------------------------------ profile-thunk16 [4] 100.0% [7] 8560(100.0%) 0(0.0%) run ...et-7.5/share/pkgs/profile-lib/main.rkt:39:2 .../more-scheme.rkt:261:28 [8] 100.0% ------------------------------------------------------------------------------ for-loop [15] 1.2% set-precompute! [48] 2.5% run [7] 96.4% [8] 8560(100.0%) 0(0.0%) .../more-scheme.rkt:261:28 (unknown source) run-improve47 [9] 81.0% prepare-points-intervals [10] 11.8% temp85_0 [11] 2.5% loop [61] 2.4% errors [12] 1.2% ??? [26] 1.2% ------------------------------------------------------------------------------ .../more-scheme.rkt:261:28 [8] 100.0% [9] 7146(83.5%) 0(0.0%) run-improve47 ...building-2/src/mainloop.rkt:346:0 simplify! [21] 97.2% for-loop [16] 2.8% ------------------------------------------------------------------------------ .../more-scheme.rkt:261:28 [8] 100.0% [10] 1008(11.8%) 0(0.0%) prepare-points-intervals ...2/src/points.rkt:120:0 loop [13] 100.0% ------------------------------------------------------------------------------ .../more-scheme.rkt:261:28 [8] 100.0% [11] 420(4.9%) 0(0.0%) temp85_0 (unknown source) compile15 [14] 100.0% ------------------------------------------------------------------------------ .../more-scheme.rkt:261:28 [8] 100.0% [12] 204(2.4%) 0(0.0%) errors ...egraph-rebuilding-2/src/points.rkt:190:0 for-loop [15] 100.0% ------------------------------------------------------------------------------ prepare-points-intervals [10] 100.0% [13] 1008(11.8%) 0(0.0%) loop ...-regraph-rebuilding-2/src/points.rkt:132:4 loop [17] 80.2% ??? [19] 19.8% ------------------------------------------------------------------------------ temp85_0 [11] 100.0% [14] 420(4.9%) 0(0.0%) compile15 (unknown source) temp91_0 [18] 100.0% ------------------------------------------------------------------------------ errors [12] 100.0% [15] 204(2.4%) 0(0.0%) for-loop ...raph-rebuilding-2/src/points.rkt:192:2 .../more-scheme.rkt:261:28 [8] 100.0% ------------------------------------------------------------------------------ run-improve47 [9] 100.0% [16] 200(2.3%) 0(0.0%) for-loop ...ph-rebuilding-2/src/mainloop.rkt:365:4 run-iter! [20] 100.0% ------------------------------------------------------------------------------ loop [13] 100.0% [17] 808(9.4%) 206(2.4%) loop ...y-regraph-rebuilding-2/src/points.rkt:79:2 ??? [26] 49.5% real->precision [23] 25.0% ------------------------------------------------------------------------------ compile15 [14] 100.0% [18] 420(4.9%) 0(0.0%) temp91_0 (unknown source) temp118_0 [22] 100.0% ------------------------------------------------------------------------------ loop [13] 100.0% [19] 200(2.3%) 0(0.0%) ??? ...y-regraph-rebuilding-2/src/points.rkt:113:6 sample-multi-bounded [24] 100.0% ------------------------------------------------------------------------------ for-loop [16] 100.0% [20] 200(2.3%) 0(0.0%) run-iter! ...h-rebuilding-2/src/mainloop.rkt:327:0 simplify! [21] 100.0% ------------------------------------------------------------------------------ run-iter! [20] 2.8% run-improve47 [9] 97.2% [21] 7146(83.5%) 0(0.0%) simplify! ...h-rebuilding-2/src/mainloop.rkt:224:0 ??? [25] 100.0% ------------------------------------------------------------------------------ temp91_0 [18] 100.0% [22] 420(4.9%) 0(0.0%) temp118_0 (unknown source) expand-capturing-lifts [27] 100.0% ------------------------------------------------------------------------------ loop [17] 100.0% [23] 202(2.4%) 0(0.0%) real->precision ...ilding-2/src/programs.rkt:94:11 ??? [26] 100.0% ------------------------------------------------------------------------------ ??? [19] 100.0% [24] 200(2.3%) 0(0.0%) sample-multi-bounded ...ding-2/src/points.rkt:14:0 random-ranges [28] 100.0% ------------------------------------------------------------------------------ simplify! [21] 100.0% [25] 7146(83.5%) 0(0.0%) ??? ...aph-rebuilding-2/src/core/simplify.rkt:38:0 ??? [29] 100.0% ------------------------------------------------------------------------------ real->precision [23] 25.1% .../more-scheme.rkt:261:28 [8] 25.3% loop [17] 49.6% [26] 806(9.4%) 406(4.7%) ??? ...tract/private/arrow-higher-order.rkt:375:33 ival-mult [31] 49.6% ------------------------------------------------------------------------------ temp118_0 [22] 100.0% [27] 420(4.9%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [30] 100.0% ------------------------------------------------------------------------------ sample-multi-bounded [24] 100.0% [28] 200(2.3%) 0(0.0%) random-ranges ...rebuilding-2/src/common.rkt:164:0 build--> [32] 100.0% ------------------------------------------------------------------------------ ??? [25] 100.0% [29] 7146(83.5%) 200(2.3%) ??? ...aph-rebuilding-2/src/core/simplify.rkt:56:0 for-loop [33] 97.2% ------------------------------------------------------------------------------ expand-capturing-lifts [27] 100.0% [30] 420(4.9%) 0(0.0%) lambda-clause-expander (unknown source) finish-bodys [34] 100.0% ------------------------------------------------------------------------------ ??? [26] 100.0% [31] 400(4.7%) 0(0.0%) ival-mult ...ebuilding-2/src/biginterval.rkt:120:0 bfmul [35] 100.0% ------------------------------------------------------------------------------ random-ranges [28] 100.0% [32] 200(2.3%) 200(2.3%) build--> ...act/private/arrow-val-first.rkt:1214:0 ------------------------------------------------------------------------------ ??? [29] 100.0% [33] 6946(81.1%) 0(0.0%) for-loop ...ebuilding-2/src/core/simplify.rkt:76:2 for-loop [36] 100.0% ------------------------------------------------------------------------------ lambda-clause-expander [30] 38.2% for-loop [37] 61.8% [34] 420(4.9%) 0(0.0%) finish-bodys (unknown source) for-loop [37] 100.0% ------------------------------------------------------------------------------ ival-mult [31] 100.0% [35] 400(4.7%) 200(2.3%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:835:4 new-mpfr [38] 50.0% ------------------------------------------------------------------------------ for-loop [33] 100.0% [36] 6946(81.1%) 0(0.0%) for-loop ...ebuilding-2/src/core/simplify.rkt:80:4 ??? [39] 88.0% ??? [40] 6.0% egraph-rebuild [41] 6.0% ------------------------------------------------------------------------------ for-loop [37] 33.3% finish-bodys [34] 66.7% [37] 420(4.9%) 0(0.0%) for-loop (unknown source) finish-bodys [34] 41.2% for-loop [37] 33.3% dispatch-transformer41 [3] 17.6% syntax-e [2] 7.9% ------------------------------------------------------------------------------ bfmul [35] 100.0% [38] 200(2.3%) 200(2.3%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:348:0 ------------------------------------------------------------------------------ for-loop [36] 100.0% [39] 6112(71.4%) 0(0.0%) ??? ...-regraph-rebuilding-2/regraph/main.rkt:45:0 find-matches [42] 59.8% for-loop [43] 40.2% ------------------------------------------------------------------------------ for-loop [36] 100.0% [40] 420(4.9%) 0(0.0%) ??? ...-regraph-rebuilding-2/regraph/main.rkt:57:0 for-loop [44] 100.0% ------------------------------------------------------------------------------ for-loop [36] 100.0% [41] 414(4.8%) 0(0.0%) egraph-rebuild ...ilding-2/regraph/egraph.rkt:61:0 egraph-rebuild-once [45] 100.0% ------------------------------------------------------------------------------ ??? [39] 100.0% [42] 3654(42.7%) 0(0.0%) find-matches ...rebuilding-2/regraph/main.rkt:37:0 for-loop [46] 100.0% ------------------------------------------------------------------------------ ??? [39] 100.0% [43] 2458(28.7%) 0(0.0%) for-loop ...aph-rebuilding-2/regraph/main.rkt:48:2 for-loop [47] 100.0% ------------------------------------------------------------------------------ ??? [40] 100.0% [44] 420(4.9%) 0(0.0%) for-loop ...aph-rebuilding-2/regraph/main.rkt:60:2 set-precompute! [48] 100.0% ------------------------------------------------------------------------------ egraph-rebuild [41] 100.0% [45] 414(4.8%) 0(0.0%) egraph-rebuild-once ...g-2/regraph/egraph.rkt:68:0 for-loop [49] 100.0% ------------------------------------------------------------------------------ for-loop [46] 50.0% find-matches [42] 50.0% [46] 3654(42.7%) 0(0.0%) for-loop ...aph-rebuilding-2/regraph/main.rkt:39:2 for-loop [46] 50.0% match-e [50] 50.0% ------------------------------------------------------------------------------ for-loop [43] 100.0% [47] 2458(28.7%) 0(0.0%) for-loop ...aph-rebuilding-2/regraph/main.rkt:51:4 add-enode-rec! [51] 58.3% substitute-e [52] 41.7% ------------------------------------------------------------------------------ for-loop [44] 100.0% [48] 420(4.9%) 0(0.0%) set-precompute! ...uilding-2/regraph/main.rkt:64:0 .../more-scheme.rkt:261:28 [8] 100.0% ------------------------------------------------------------------------------ egraph-rebuild-once [45] 100.0% [49] 414(4.8%) 0(0.0%) for-loop ...h-rebuilding-2/regraph/egraph.rkt:72:2 for-loop [53] 100.0% ------------------------------------------------------------------------------ for-loop [60] 36.3% for-loop [46] 63.7% [50] 3654(42.7%) 0(0.0%) match-e ...ph-rebuilding-2/regraph/ematch.rkt:35:0 for-loop [54] 100.0% ------------------------------------------------------------------------------ for-loop [47] 100.0% [51] 1434(16.8%) 0(0.0%) add-enode-rec! ...lding-2/regraph/egraph.rkt:161:0 mk-enode! [55] 70.9% map [56] 29.1% ------------------------------------------------------------------------------ substitute-e [52] 40.2% for-loop [47] 59.8% [52] 1024(12.0%) 214(2.5%) substitute-e ...building-2/regraph/ematch.rkt:55:0 substitute-e [52] 40.2% do-curry [57] 29.6% ??? [62] 10.0% return/no-unsupplied [59] 9.8% ------------------------------------------------------------------------------ for-loop [49] 100.0% [53] 414(4.8%) 196(2.3%) for-loop ...h-rebuilding-2/regraph/egraph.rkt:73:4 for-loop [58] 52.7% ------------------------------------------------------------------------------ match-e [50] 100.0% [54] 3654(42.7%) 1832(21.4%) for-loop ...-rebuilding-2/regraph/ematch.rkt:41:11 for-loop [60] 36.3% loop [61] 11.0% procedure-reduce-keyword-arity-mask [64] 8.3% foldr [63] 5.7% step [71] 5.5% cartesian-product [68] 2.7% foldl [69] 2.7% ------------------------------------------------------------------------------ add-enode-rec! [51] 100.0% [55] 1016(11.9%) 606(7.1%) mk-enode! ...rebuilding-2/regraph/egraph.rkt:132:0 hash-has-key? [65] 20.5% mutable-set [66] 19.9% ------------------------------------------------------------------------------ add-enode-rec! [51] 100.0% [56] 418(4.9%) 0(0.0%) map ...t-7.5/collects/racket/private/map.rkt:35:13 ??? [62] 52.2% mk-enode-rec! [67] 47.8% ------------------------------------------------------------------------------ substitute-e [52] 100.0% [57] 406(4.7%) 406(4.7%) do-curry ...-7.5/collects/racket/function.rkt:71:2 ------------------------------------------------------------------------------ for-loop [53] 100.0% [58] 218(2.5%) 218(2.5%) for-loop ...-rebuilding-2/regraph/egraph.rkt:245:6 ------------------------------------------------------------------------------ substitute-e [52] 100.0% [59] 200(2.3%) 200(2.3%) return/no-unsupplied ...ct/private/arr-i.rkt:566:0 ------------------------------------------------------------------------------ for-loop [54] 100.0% [60] 2650(31.0%) 0(0.0%) for-loop ...-rebuilding-2/regraph/ematch.rkt:47:37 match-e [50] 100.0% ------------------------------------------------------------------------------ apply-transformer52 [6] 10.8% loop [61] 19.4% .../more-scheme.rkt:261:28 [8] 19.7% for-loop [54] 19.9% syntax-e/no-taint [5] 30.2% [61] 1024(12.0%) 202(2.4%) loop (unknown source) gf [70] 30.2% eval [73] 19.7% step [71] 19.5% loop [61] 19.4% syntax-e [2] 10.8% ------------------------------------------------------------------------------ substitute-e [52] 48.3% map [56] 51.7% [62] 422(4.9%) 422(4.9%) ??? ...cket-7.5/collects/racket/function.rkt:93:12 ------------------------------------------------------------------------------ for-loop [54] 100.0% [63] 418(4.9%) 418(4.9%) foldr ....5/collects/racket/private/list.rkt:242:4 ------------------------------------------------------------------------------ for-loop [54] 100.0% [64] 402(4.7%) 402(4.7%) procedure-reduce-keyword-arity-mask ....rkt:1597:4 ------------------------------------------------------------------------------ mk-enode! [55] 100.0% [65] 208(2.4%) 208(2.4%) hash-has-key? ...ket/private/more-scheme.rkt:371:2 ------------------------------------------------------------------------------ mk-enode! [55] 100.0% [66] 202(2.4%) 0(0.0%) mutable-set ...racket/private/set-types.rkt:1010:0 for-loop [72] 100.0% ------------------------------------------------------------------------------ map [56] 100.0% [67] 200(2.3%) 200(2.3%) mk-enode-rec! ...ilding-2/regraph/egraph.rkt:152:0 ------------------------------------------------------------------------------ for-loop [54] 100.0% [68] 200(2.3%) 200(2.3%) cartesian-product ...ollects/racket/list.rkt:822:0 ------------------------------------------------------------------------------ for-loop [54] 100.0% [69] 200(2.3%) 200(2.3%) foldl ....5/collects/racket/private/list.rkt:229:4 ------------------------------------------------------------------------------ loop [61] 100.0% [70] 420(4.9%) 0(0.0%) gf (unknown source) cache-or-reuse-set [74] 52.9% propagation-apply [76] 47.1% ------------------------------------------------------------------------------ for-loop [54] 50.0% loop [61] 50.0% [71] 400(4.7%) 400(4.7%) step ...ket-7.5/collects/racket/function.rkt:145:9 ------------------------------------------------------------------------------ mutable-set [66] 100.0% [72] 202(2.4%) 202(2.4%) for-loop ...cts/racket/private/set-types.rkt:954:2 ------------------------------------------------------------------------------ loop [61] 100.0% [73] 202(2.4%) 0(0.0%) eval (unknown source) namespace-syntax-introduce3 [75] 100.0% ------------------------------------------------------------------------------ gf [70] 100.0% [74] 222(2.6%) 222(2.6%) cache-or-reuse-set (unknown source) ------------------------------------------------------------------------------ eval [73] 100.0% [75] 202(2.4%) 202(2.4%) namespace-syntax-introduce3 (unknown source) ------------------------------------------------------------------------------ gf [70] 100.0% [76] 198(2.3%) 198(2.3%) propagation-apply (unknown source) ------------------------------------------------------------------------------