Profiling results ----------------- Total cpu time observed: 14858ms (out of 15052ms) Number of samples taken: 73 (once every 204ms) =============================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee =============================================================================== [1] 14858(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [3] 100.0% ------------------------------------------------------------------------------- for-loop [44] 100.0% [2] 192(1.3%) 0(0.0%) syntax-e (unknown source) syntax-e/no-taint [4] 100.0% ------------------------------------------------------------------------------- ??? [1] 100.0% [3] 14858(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [5] 100.0% ------------------------------------------------------------------------------- syntax-e [2] 100.0% [4] 192(1.3%) 0(0.0%) syntax-e/no-taint (unknown source) loop [77] 100.0% ------------------------------------------------------------------------------- profile-thunk16 [3] 100.0% [5] 14858(100.0%) 0(0.0%) run ...et-7.5/share/pkgs/profile-lib/main.rkt:39:2 .../more-scheme.rkt:261:28 [6] 100.0% ------------------------------------------------------------------------------- for-loop [12] 0.7% run [5] 99.3% [6] 14858(100.0%) 200(1.3%) .../more-scheme.rkt:261:28 (unknown source) run-improve47 [7] 89.2% prepare-points-intervals [8] 8.1% errors [9] 2.0% ------------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [6] 100.0% [7] 13260(89.2%) 0(0.0%) run-improve47 ...fy-regraph/src/mainloop.rkt:346:0 for-loop [10] 100.0% ------------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [6] 100.0% [8] 1206(8.1%) 0(0.0%) prepare-points-intervals ...h/src/points.rkt:120:0 loop [11] 100.0% ------------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [6] 100.0% [9] 392(2.6%) 0(0.0%) errors ...ie/simplify-regraph/src/points.rkt:190:0 for-loop [12] 51.0% temp85_0 [13] 49.0% ------------------------------------------------------------------------------- run-improve47 [7] 100.0% [10] 13260(89.2%) 0(0.0%) for-loop ...implify-regraph/src/mainloop.rkt:365:4 run-iter! [14] 100.0% ------------------------------------------------------------------------------- prepare-points-intervals [8] 100.0% [11] 1206(8.1%) 0(0.0%) loop ...rbie/simplify-regraph/src/points.rkt:132:4 loop [15] 50.1% ??? [16] 49.9% ------------------------------------------------------------------------------- errors [9] 100.0% [12] 200(1.3%) 0(0.0%) for-loop .../simplify-regraph/src/points.rkt:192:2 .../more-scheme.rkt:261:28 [6] 100.0% ------------------------------------------------------------------------------- errors [9] 100.0% [13] 192(1.3%) 0(0.0%) temp85_0 (unknown source) compile15 [17] 100.0% ------------------------------------------------------------------------------- for-loop [10] 100.0% [14] 13260(89.2%) 0(0.0%) run-iter! ...mplify-regraph/src/mainloop.rkt:327:0 simplify! [18] 100.0% ------------------------------------------------------------------------------- loop [11] 100.0% [15] 604(4.1%) 0(0.0%) loop ...erbie/simplify-regraph/src/points.rkt:79:2 real->precision [19] 34.1% ??? [24] 33.1% ??? [21] 32.8% ------------------------------------------------------------------------------- loop [11] 100.0% [16] 602(4.1%) 402(2.7%) ??? ...erbie/simplify-regraph/src/points.rkt:113:6 sample-multi-bounded [20] 33.2% ------------------------------------------------------------------------------- temp85_0 [13] 100.0% [17] 192(1.3%) 0(0.0%) compile15 (unknown source) temp91_0 [22] 100.0% ------------------------------------------------------------------------------- run-iter! [14] 100.0% [18] 13260(89.2%) 0(0.0%) simplify! ...mplify-regraph/src/mainloop.rkt:224:0 ??? [23] 100.0% ------------------------------------------------------------------------------- loop [15] 100.0% [19] 206(1.4%) 0(0.0%) real->precision ...-regraph/src/programs.rkt:94:11 ??? [24] 100.0% ------------------------------------------------------------------------------- ??? [16] 100.0% [20] 200(1.3%) 0(0.0%) sample-multi-bounded ...egraph/src/points.rkt:14:0 random-ranges [25] 100.0% ------------------------------------------------------------------------------- loop [15] 100.0% [21] 198(1.3%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:379:33 ival-add [26] 100.0% ------------------------------------------------------------------------------- compile15 [17] 100.0% [22] 192(1.3%) 0(0.0%) temp91_0 (unknown source) temp118_0 [27] 100.0% ------------------------------------------------------------------------------- simplify! [18] 100.0% [23] 13260(89.2%) 0(0.0%) ??? ...simplify-regraph/src/core/simplify.rkt:38:0 ??? [28] 100.0% ------------------------------------------------------------------------------- loop [15] 49.3% real->precision [19] 50.7% [24] 406(2.7%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:375:33 bf [29] 50.7% ival-mult [30] 49.3% ------------------------------------------------------------------------------- sample-multi-bounded [20] 100.0% [25] 200(1.3%) 0(0.0%) random-ranges ...lify-regraph/src/common.rkt:164:0 cons/c [31] 100.0% ------------------------------------------------------------------------------- ??? [21] 100.0% [26] 198(1.3%) 0(0.0%) ival-add ...lify-regraph/src/biginterval.rkt:102:0 bfadd [32] 100.0% ------------------------------------------------------------------------------- temp91_0 [22] 100.0% [27] 192(1.3%) 0(0.0%) temp118_0 (unknown source) expand-capturing-lifts [33] 100.0% ------------------------------------------------------------------------------- ??? [23] 100.0% [28] 13260(89.2%) 0(0.0%) ??? ...simplify-regraph/src/core/simplify.rkt:56:0 for-loop [34] 100.0% ------------------------------------------------------------------------------- ??? [24] 100.0% [29] 206(1.4%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:615:2 ??? [35] 100.0% ------------------------------------------------------------------------------- ??? [24] 100.0% [30] 200(1.3%) 200(1.3%) ival-mult ...ify-regraph/src/biginterval.rkt:120:0 ------------------------------------------------------------------------------- random-ranges [25] 100.0% [31] 200(1.3%) 0(0.0%) cons/c .../racket/contract/private/guts.rkt:500:15 coerce-contract [36] 100.0% ------------------------------------------------------------------------------- ival-add [26] 100.0% [32] 198(1.3%) 0(0.0%) bfadd ...-lib/math/private/bigfloat/mpfr.rkt:835:4 ??? [35] 100.0% ------------------------------------------------------------------------------- temp118_0 [27] 100.0% [33] 192(1.3%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [37] 100.0% ------------------------------------------------------------------------------- ??? [28] 100.0% [34] 13260(89.2%) 0(0.0%) for-loop ...ify-regraph/src/core/simplify.rkt:76:2 for-loop [38] 100.0% ------------------------------------------------------------------------------- bfadd [32] 49.0% bf [29] 51.0% [35] 404(2.7%) 404(2.7%) ??? /opt/racket-7.5/collects/ffi/unsafe.rkt:938:4 ------------------------------------------------------------------------------- cons/c [31] 100.0% [36] 200(1.3%) 0(0.0%) coerce-contract ...contract/private/guts.rkt:307:0 coerce-contract/f [39] 100.0% ------------------------------------------------------------------------------- expand-capturing-lifts [33] 100.0% [37] 192(1.3%) 0(0.0%) lambda-clause-expander (unknown source) finish-bodys [40] 100.0% ------------------------------------------------------------------------------- for-loop [34] 100.0% [38] 13260(89.2%) 0(0.0%) for-loop ...ify-regraph/src/core/simplify.rkt:80:4 ??? [41] 98.5% prune-phase [42] 1.5% ------------------------------------------------------------------------------- coerce-contract [36] 100.0% [39] 200(1.3%) 0(0.0%) coerce-contract/f ...ntract/private/guts.rkt:372:0 coerce-simple-value [43] 100.0% ------------------------------------------------------------------------------- lambda-clause-expander [37] 25.0% for-loop [44] 75.0% [40] 192(1.3%) 0(0.0%) finish-bodys (unknown source) for-loop [44] 100.0% ------------------------------------------------------------------------------- for-loop [38] 100.0% [41] 13056(87.9%) 0(0.0%) ??? .../p92/.racket/7.5/pkgs/regraph/main.rkt:42:0 for-loop [45] 73.5% find-matches [46] 26.5% ------------------------------------------------------------------------------- for-loop [38] 100.0% [42] 204(1.4%) 0(0.0%) prune-phase ...cket/7.5/pkgs/regraph/main.rkt:70:0 for-loop [47] 100.0% ------------------------------------------------------------------------------- coerce-contract/f [39] 100.0% [43] 200(1.3%) 200(1.3%) coerce-simple-value ...ract/private/guts.rkt:385:0 ------------------------------------------------------------------------------- for-loop [44] 33.3% finish-bodys [40] 66.7% [44] 192(1.3%) 0(0.0%) for-loop (unknown source) finish-bodys [40] 50.0% for-loop [44] 33.3% syntax-e [2] 16.7% ------------------------------------------------------------------------------- ??? [41] 100.0% [45] 9596(64.6%) 0(0.0%) for-loop ....racket/7.5/pkgs/regraph/main.rkt:45:2 for-loop [48] 100.0% ------------------------------------------------------------------------------- ??? [41] 100.0% [46] 3460(23.3%) 0(0.0%) find-matches ...ket/7.5/pkgs/regraph/main.rkt:34:0 for-loop [49] 100.0% ------------------------------------------------------------------------------- prune-phase [42] 100.0% [47] 204(1.4%) 0(0.0%) for-loop ....racket/7.5/pkgs/regraph/main.rkt:73:2 update-leader! [56] 100.0% ------------------------------------------------------------------------------- for-loop [45] 100.0% [48] 9596(64.6%) 0(0.0%) for-loop ....racket/7.5/pkgs/regraph/main.rkt:48:4 merge-egraph-nodes! [50] 48.9% dedup-children! [60] 40.6% substitute-e [52] 8.4% mk-enode-rec! [53] 2.1% ------------------------------------------------------------------------------- for-loop [49] 50.0% find-matches [46] 50.0% [49] 3460(23.3%) 0(0.0%) for-loop ....racket/7.5/pkgs/regraph/main.rkt:36:2 for-loop [49] 50.0% match-e [51] 50.0% ------------------------------------------------------------------------------- for-loop [57] 3.6% merge-egraph-nodes! [50] 10.0% for-loop [48] 86.4% [50] 4692(31.6%) 0(0.0%) merge-egraph-nodes! ...gs/regraph/egraph.rkt:144:0 loop! [55] 58.9% update-leader! [56] 23.2% merge-egraph-nodes! [50] 10.0% for-loop [57] 7.9% ------------------------------------------------------------------------------- for-loop [63] 24.2% for-loop [49] 75.8% [51] 3460(23.3%) 404(2.7%) match-e ...racket/7.5/pkgs/regraph/ematch.rkt:35:0 for-loop [54] 92.2% ------------------------------------------------------------------------------- substitute-e [52] 12.6% for-loop [48] 87.4% [52] 804(5.4%) 604(4.1%) substitute-e ...t/7.5/pkgs/regraph/ematch.rkt:55:0 return/no-unsupplied [59] 24.9% substitute-e [52] 12.6% ------------------------------------------------------------------------------- for-loop [48] 100.0% [53] 204(1.4%) 0(0.0%) mk-enode-rec! ...7.5/pkgs/regraph/egraph.rkt:116:0 new-enode [58] 100.0% ------------------------------------------------------------------------------- match-e [51] 100.0% [54] 3258(21.9%) 1200(8.1%) for-loop ...cket/7.5/pkgs/regraph/ematch.rkt:41:11 for-loop [63] 27.7% loop [77] 19.8% procedure-reduce-keyword-arity-mask [65] 12.5% do-curry [66] 6.2% foldl [67] 3.1% foldr [70] 3.1% keyword-apply [68] 3.1% ------------------------------------------------------------------------------- loop [77] 19.7% merge-egraph-nodes! [50] 80.3% [55] 3068(20.6%) 798(5.4%) loop! .../.racket/7.5/pkgs/regraph/enode.rkt:158:2 custom-set-union [61] 46.9% loop [77] 27.0% set [64] 6.6% ------------------------------------------------------------------------------- for-loop [47] 12.5% merge-egraph-nodes! [50] 87.5% [56] 1628(11.0%) 0(0.0%) update-leader! ....5/pkgs/regraph/egraph.rkt:211:0 for-loop [62] 100.0% ------------------------------------------------------------------------------- merge-egraph-nodes! [50] 100.0% [57] 606(4.1%) 0(0.0%) for-loop ...cket/7.5/pkgs/regraph/egraph.rkt:197:4 merge-egraph-nodes! [50] 67.0% dedup-children! [60] 33.0% ------------------------------------------------------------------------------- mk-enode-rec! [53] 100.0% [58] 204(1.4%) 0(0.0%) new-enode ...acket/7.5/pkgs/regraph/enode.rkt:64:0 set [64] 100.0% ------------------------------------------------------------------------------- substitute-e [52] 100.0% [59] 200(1.3%) 0(0.0%) return/no-unsupplied ...ct/private/arr-i.rkt:566:0 filter [69] 100.0% ------------------------------------------------------------------------------- for-loop [57] 4.9% for-loop [48] 95.1% [60] 4096(27.6%) 0(0.0%) dedup-children! ....5/pkgs/regraph/enode.rkt:166:0 loop [71] 95.2% loop [76] 4.8% ------------------------------------------------------------------------------- loop! [55] 100.0% [61] 1644(11.1%) 626(4.2%) custom-set-union ...et/private/set-types.rkt:168:0 for-loop [72] 48.8% for-loop [74] 13.1% ------------------------------------------------------------------------------- update-leader! [56] 100.0% [62] 1628(11.0%) 200(1.3%) for-loop ...cket/7.5/pkgs/regraph/egraph.rkt:216:6 hash-update! [78] 62.5% for-loop [73] 25.2% ------------------------------------------------------------------------------- for-loop [54] 100.0% [63] 1604(10.8%) 0(0.0%) for-loop ...cket/7.5/pkgs/regraph/ematch.rkt:47:37 match-e [51] 100.0% ------------------------------------------------------------------------------- new-enode [58] 33.7% loop! [55] 66.3% [64] 606(4.1%) 606(4.1%) set ...collects/racket/private/set-types.rkt:992:0 ------------------------------------------------------------------------------- for-loop [54] 100.0% [65] 408(2.7%) 408(2.7%) procedure-reduce-keyword-arity-mask ....rkt:1597:4 ------------------------------------------------------------------------------- for-loop [54] 100.0% [66] 202(1.4%) 202(1.4%) do-curry ...-7.5/collects/racket/function.rkt:71:2 ------------------------------------------------------------------------------- for-loop [54] 100.0% [67] 202(1.4%) 202(1.4%) foldl ....5/collects/racket/private/list.rkt:229:4 ------------------------------------------------------------------------------- for-loop [54] 100.0% [68] 200(1.3%) 200(1.3%) keyword-apply ...lects/racket/private/kw.rkt:455:2 ------------------------------------------------------------------------------- return/no-unsupplied [59] 100.0% [69] 200(1.3%) 200(1.3%) filter ...5/collects/racket/private/list.rkt:256:2 ------------------------------------------------------------------------------- for-loop [54] 100.0% [70] 200(1.3%) 0(0.0%) foldr ....5/collects/racket/private/list.rkt:242:4 cp-2 [75] 100.0% ------------------------------------------------------------------------------- dedup-children! [60] 8.9% loop [71] 91.1% [71] 3898(26.2%) 3898(26.2%) loop ...racket-7.5/collects/racket/list.rkt:434:17 loop [71] 91.1% ------------------------------------------------------------------------------- custom-set-union [61] 100.0% [72] 802(5.4%) 402(2.7%) for-loop ...cts/racket/private/set-types.rkt:174:3 for-loop [79] 49.9% ------------------------------------------------------------------------------- for-loop [62] 100.0% [73] 410(2.8%) 0(0.0%) for-loop ...cket/7.5/pkgs/regraph/egraph.rkt:217:8 hash-update! [78] 100.0% ------------------------------------------------------------------------------- custom-set-union [61] 100.0% [74] 216(1.5%) 216(1.5%) for-loop ...cts/racket/private/set-types.rkt:152:2 ------------------------------------------------------------------------------- foldr [70] 100.0% [75] 200(1.3%) 200(1.3%) cp-2 .../racket-7.5/collects/racket/list.rkt:826:2 ------------------------------------------------------------------------------- dedup-children! [60] 10.0% loop [76] 90.0% [76] 198(1.3%) 0(0.0%) loop ...racket-7.5/collects/racket/list.rkt:417:34 loop [76] 90.0% loop [77] 10.0% ------------------------------------------------------------------------------- for-loop [54] 2.8% loop! [55] 6.4% syntax-e/no-taint [4] 7.8% loop [76] 8.0% loop [77] 74.9% [77] 2466(16.6%) 842(5.7%) loop (unknown source) loop [77] 74.9% gf [82] 7.8% loop! [55] 4.6% step [81] 0.1% ------------------------------------------------------------------------------- for-loop [73] 28.7% for-loop [62] 71.3% [78] 1428(9.6%) 0(0.0%) hash-update! ...cket/private/more-scheme.rkt:365:4 ??? [80] 100.0% ------------------------------------------------------------------------------- for-loop [72] 100.0% [79] 400(2.7%) 400(2.7%) for-loop ...cts/racket/private/set-types.rkt:178:5 ------------------------------------------------------------------------------- hash-update! [78] 100.0% [80] 1428(9.6%) 216(1.5%) ??? .../.racket/7.5/pkgs/regraph/egraph.rkt:219:24 for-loop [83] 70.4% update-en-expr [85] 14.4% ------------------------------------------------------------------------------- loop [77] 100.0% [81] 224(1.5%) 224(1.5%) step ...ket-7.5/collects/racket/function.rkt:145:9 ------------------------------------------------------------------------------- loop [77] 100.0% [82] 192(1.3%) 0(0.0%) gf (unknown source) propagation-merge [84] 100.0% ------------------------------------------------------------------------------- ??? [80] 100.0% [83] 1006(6.8%) 806(5.4%) for-loop ...ket/7.5/pkgs/regraph/egraph.rkt:220:26 update-en-expr [85] 19.9% ------------------------------------------------------------------------------- gf [82] 100.0% [84] 192(1.3%) 192(1.3%) propagation-merge (unknown source) ------------------------------------------------------------------------------- for-loop [83] 49.3% ??? [80] 50.7% [85] 406(2.7%) 406(2.7%) update-en-expr ....5/pkgs/regraph/egraph.rkt:205:0 -------------------------------------------------------------------------------