Profiling results ----------------- Total cpu time observed: 91180ms (out of 91716ms) Number of samples taken: 287 (once every 318ms) (Hiding functions with self<1.0% and local<2.0%: 1 of 76 hidden) ================================================================================ Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ================================================================================ ??? [5] 100.0% [1] 91180(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [3] 100.0% -------------------------------------------------------------------------------- ??? [5] 100.0% [2] 86168(94.5%) 0(0.0%) run-improve47 ...rface-bugs/src/mainloop.rkt:345:0 simplify! [4] 100.0% -------------------------------------------------------------------------------- profile-thunk16 [1] 100.0% [3] 91180(100.0%) 0(0.0%) run ...et-7.2/share/pkgs/profile-lib/main.rkt:39:2 ??? [5] 100.0% -------------------------------------------------------------------------------- run-improve47 [2] 100.0% [4] 86168(94.5%) 0(0.0%) simplify! ...interface-bugs/src/mainloop.rkt:223:0 ??? [6] 100.0% -------------------------------------------------------------------------------- run [3] 50.0% [5] 91180(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 50.0% run-improve47 [2] 47.3% prepare-points-intervals [8] 2.6% eval-errors [9] 0.1% -------------------------------------------------------------------------------- simplify! [4] 100.0% [6] 86168(94.5%) 0(0.0%) ??? ...x-interface-bugs/src/core/simplify.rkt:26:0 for-loop [7] 100.0% -------------------------------------------------------------------------------- ??? [6] 100.0% [7] 86168(94.5%) 0(0.0%) for-loop ...erface-bugs/src/core/simplify.rkt:35:2 one-iter [10] 100.0% -------------------------------------------------------------------------------- ??? [5] 100.0% [8] 4780(5.2%) 0(0.0%) prepare-points-intervals ...s/src/points.rkt:121:0 loop [11] 100.0% -------------------------------------------------------------------------------- ??? [5] 100.0% [9] 232(0.3%) 0(0.0%) eval-errors ...interface-bugs/src/points.rkt:190:0 for-loop [12] 100.0% -------------------------------------------------------------------------------- for-loop [7] 100.0% [10] 86168(94.5%) 0(0.0%) one-iter ...erface-bugs/src/core/simplify.rkt:72:0 for-loop [13] 94.3% find-matches [14] 5.4% for-loop [17] 0.3% -------------------------------------------------------------------------------- prepare-points-intervals [8] 100.0% [11] 4780(5.2%) 0(0.0%) loop ...ie/fix-interface-bugs/src/points.rkt:134:4 loop [15] 67.0% ??? [16] 33.0% -------------------------------------------------------------------------------- eval-errors [9] 100.0% [12] 232(0.3%) 0(0.0%) for-loop ...ix-interface-bugs/src/points.rkt:192:2 ??? [20] 100.0% -------------------------------------------------------------------------------- one-iter [10] 100.0% [13] 81230(89.1%) 594(0.7%) for-loop ...erface-bugs/src/core/simplify.rkt:74:2 for-loop [18] 99.3% -------------------------------------------------------------------------------- one-iter [10] 100.0% [14] 4640(5.1%) 0(0.0%) find-matches ...ce-bugs/src/core/simplify.rkt:62:0 for-loop [19] 100.0% -------------------------------------------------------------------------------- loop [11] 100.0% [15] 3202(3.5%) 0(0.0%) loop ...bie/fix-interface-bugs/src/points.rkt:80:2 ??? [20] 91.2% hash-update! [65] 8.8% -------------------------------------------------------------------------------- loop [11] 100.0% [16] 1578(1.7%) 528(0.6%) ??? ...bie/fix-interface-bugs/src/points.rkt:114:6 sample-multi-bounded [21] 66.5% -------------------------------------------------------------------------------- one-iter [10] 100.0% [17] 298(0.3%) 0(0.0%) for-loop ...erface-bugs/src/core/simplify.rkt:84:2 update-leader! [30] 100.0% -------------------------------------------------------------------------------- for-loop [13] 100.0% [18] 80636(88.4%) 300(0.3%) for-loop ...erface-bugs/src/core/simplify.rkt:77:4 merge-egraph-nodes! [22] 58.8% dedup-children! [29] 35.8% pattern-substitute [24] 3.1% mk-enode-rec! [26] 1.9% -------------------------------------------------------------------------------- for-loop [19] 50.0% find-matches [14] 50.0% [19] 4640(5.1%) 302(0.3%) for-loop ...erface-bugs/src/core/simplify.rkt:64:8 for-loop [19] 50.0% match-e [23] 46.7% -------------------------------------------------------------------------------- for-loop [12] 7.4% loop [15] 92.6% [20] 3152(3.5%) 0(0.0%) ??? ...e/fix-interface-bugs/src/programs.rkt:115:2 ??? [33] 50.3% ??? [25] 49.7% -------------------------------------------------------------------------------- ??? [16] 100.0% [21] 1050(1.2%) 0(0.0%) sample-multi-bounded ...e-bugs/src/points.rkt:15:0 random-ranges [27] 100.0% -------------------------------------------------------------------------------- merge-egraph-nodes! [22] 3.5% for-loop [31] 4.6% for-loop [18] 91.8% [22] 47454(52.0%) 336(0.4%) merge-egraph-nodes! ...s/src/core/egraph.rkt:145:0 loop! [28] 78.0% update-leader! [30] 9.5% for-loop [31] 4.6% merge-egraph-nodes! [22] 3.5% dedup-children! [29] 3.0% enode-merge! [40] 0.6% -------------------------------------------------------------------------------- for-loop [47] 30.8% for-loop [19] 69.2% [23] 4338(4.8%) 0(0.0%) match-e ...interface-bugs/src/core/ematch.rkt:39:0 for-loop [32] 90.4% ??? [34] 5.8% constant? [72] 3.8% -------------------------------------------------------------------------------- pattern-substitute [24] 35.4% for-loop [18] 64.6% [24] 2506(2.7%) 936(1.0%) pattern-substitute ...gs/src/core/matcher.rkt:46:0 pattern-substitute [24] 35.4% ??? [34] 10.3% ??? [37] 7.1% do-curry [52] 6.0% variable? [41] 3.9% -------------------------------------------------------------------------------- ??? [20] 100.0% [25] 1568(1.7%) 0(0.0%) ??? ...t-7.2/collects/racket/private/kw.rkt:594:14 ??? [33] 66.7% ??? [36] 33.3% -------------------------------------------------------------------------------- loop [44] 9.8% for-loop [18] 90.2% [26] 1526(1.7%) 614(0.7%) mk-enode-rec! ...ce-bugs/src/core/egraph.rkt:117:0 do-curry [52] 20.6% for-loop [39] 19.5% loop [44] 9.8% hash-has-key? [38] 9.8% -------------------------------------------------------------------------------- sample-multi-bounded [21] 100.0% [27] 1050(1.2%) 0(0.0%) random-ranges ...terface-bugs/src/common.rkt:171:0 cons/c [35] 73.1% build--> [42] 26.9% -------------------------------------------------------------------------------- loop [44] 26.0% merge-egraph-nodes! [22] 74.0% [28] 39292(43.1%) 9552(10.5%) loop! ...x-interface-bugs/src/core/enode.rkt:194:2 custom-set-union [57] 46.8% loop [44] 26.9% set [45] 12.2% update-en-expr [75] 0.4% -------------------------------------------------------------------------------- merge-egraph-nodes! [22] 8.2% for-loop [18] 91.8% [29] 31442(34.5%) 300(0.3%) dedup-children! ...e-bugs/src/core/enode.rkt:202:0 loop [43] 96.2% loop [53] 2.9% -------------------------------------------------------------------------------- for-loop [17] 5.7% merge-egraph-nodes! [22] 94.3% [30] 5234(5.7%) 298(0.3%) update-leader! ...e-bugs/src/core/egraph.rkt:213:0 for-loop [46] 94.3% -------------------------------------------------------------------------------- merge-egraph-nodes! [22] 100.0% [31] 4676(5.1%) 0(0.0%) for-loop ...terface-bugs/src/core/egraph.rkt:199:4 merge-egraph-nodes! [22] 100.0% -------------------------------------------------------------------------------- match-e [23] 100.0% [32] 4338(4.8%) 1238(1.4%) for-loop ...terface-bugs/src/core/ematch.rkt:52:11 for-loop [47] 47.2% procedure-reduce-keyword-arity-mask [48] 21.9% loop [44] 6.9% do-curry [52] 3.5% -------------------------------------------------------------------------------- ??? [25] 39.8% ??? [20] 60.2% [33] 2630(2.9%) 530(0.6%) ??? ...tract/private/arrow-higher-order.rkt:360:33 bf [49] 40.1% ival-mult [51] 39.8% -------------------------------------------------------------------------------- match-e [23] 49.1% pattern-substitute [24] 50.9% [34] 1222(1.3%) 1222(1.3%) ??? ...contract/private/arrow-val-first.rkt:390:18 -------------------------------------------------------------------------------- random-ranges [27] 100.0% [35] 768(0.8%) 0(0.0%) cons/c .../racket/contract/private/guts.rkt:504:15 coerce-contract [50] 100.0% -------------------------------------------------------------------------------- ??? [25] 100.0% [36] 522(0.6%) 274(0.3%) ??? ...tract/private/arrow-higher-order.rkt:364:33 ival-sub [56] 47.5% -------------------------------------------------------------------------------- pattern-substitute [24] 100.0% [37] 354(0.4%) 0(0.0%) ??? ...ects/racket/contract/private/guts.rkt:772:8 dict? [54] 100.0% -------------------------------------------------------------------------------- mk-enode-rec! [26] 100.0% [38] 300(0.3%) 300(0.3%) hash-has-key? ...ket/private/more-scheme.rkt:371:2 -------------------------------------------------------------------------------- mk-enode-rec! [26] 100.0% [39] 298(0.3%) 298(0.3%) for-loop ...erface-bugs/src/core/egraph.rkt:109:10 -------------------------------------------------------------------------------- merge-egraph-nodes! [22] 100.0% [40] 298(0.3%) 0(0.0%) enode-merge! ...face-bugs/src/core/enode.rkt:131:0 adopt-enode! [55] 100.0% -------------------------------------------------------------------------------- pattern-substitute [24] 100.0% [41] 294(0.3%) 0(0.0%) variable? ...face-bugs/src/syntax/syntax.rkt:729:0 value? [74] 100.0% -------------------------------------------------------------------------------- random-ranges [27] 100.0% [42] 282(0.3%) 0(0.0%) build--> ...act/private/arrow-val-first.rkt:1088:0 coerce-contract [50] 100.0% -------------------------------------------------------------------------------- dedup-children! [29] 7.2% loop [43] 92.8% [43] 30232(33.2%) 30232(33.2%) loop ...racket-7.2/collects/racket/list.rkt:434:17 loop [43] 92.8% -------------------------------------------------------------------------------- for-loop [32] 0.3% mk-enode-rec! [26] 1.4% loop! [28] 6.8% loop [44] 91.5% [44] 21378(23.4%) 634(0.7%) loop ...-7.2/collects/racket/private/map.rkt:40:19 loop [44] 91.5% loop! [28] 6.8% mk-enode-rec! [26] 1.4% -------------------------------------------------------------------------------- loop! [28] 100.0% [45] 9602(10.5%) 9602(10.5%) set ...collects/racket/private/set-types.rkt:981:0 -------------------------------------------------------------------------------- update-leader! [30] 100.0% [46] 4936(5.4%) 336(0.4%) for-loop ...terface-bugs/src/core/egraph.rkt:218:6 hash-update! [65] 55.6% for-loop [58] 37.6% -------------------------------------------------------------------------------- for-loop [32] 100.0% [47] 2766(3.0%) 0(0.0%) for-loop ...terface-bugs/src/core/ematch.rkt:58:37 match-e [23] 89.2% ??? [63] 10.8% -------------------------------------------------------------------------------- for-loop [32] 100.0% [48] 1268(1.4%) 1268(1.4%) procedure-reduce-keyword-arity-mask ....rkt:1562:4 -------------------------------------------------------------------------------- ??? [33] 100.0% [49] 1054(1.2%) 242(0.3%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [67] 51.4% ??? [68] 25.6% -------------------------------------------------------------------------------- build--> [42] 26.9% cons/c [35] 73.1% [50] 1050(1.2%) 0(0.0%) coerce-contract ...contract/private/guts.rkt:308:0 coerce-contract/f [59] 100.0% -------------------------------------------------------------------------------- ??? [33] 100.0% [51] 1046(1.1%) 274(0.3%) ival-mult ...erface-bugs/src/biginterval.rkt:112:0 bfmul [60] 73.8% -------------------------------------------------------------------------------- for-loop [32] 32.8% pattern-substitute [24] 32.8% mk-enode-rec! [26] 34.4% [52] 914(1.0%) 914(1.0%) do-curry ...-7.2/collects/racket/function.rkt:71:2 -------------------------------------------------------------------------------- dedup-children! [29] 14.2% loop [53] 85.8% [53] 910(1.0%) 0(0.0%) loop ...racket-7.2/collects/racket/list.rkt:417:34 loop [53] 85.8% loop [61] 7.4% member [62] 6.8% -------------------------------------------------------------------------------- ??? [37] 100.0% [54] 354(0.4%) 354(0.4%) dict? ...ollects/racket/private/generic.rkt:146:11 -------------------------------------------------------------------------------- enode-merge! [40] 100.0% [55] 298(0.3%) 0(0.0%) adopt-enode! ...face-bugs/src/core/enode.rkt:109:0 custom-set-union [57] 100.0% -------------------------------------------------------------------------------- ??? [36] 100.0% [56] 248(0.3%) 248(0.3%) ival-sub ...terface-bugs/src/biginterval.rkt:100:0 -------------------------------------------------------------------------------- adopt-enode! [55] 1.5% loop! [28] 98.5% [57] 19796(21.7%) 7772(8.5%) custom-set-union ...et/private/set-types.rkt:168:0 for-loop [64] 59.2% -------------------------------------------------------------------------------- for-loop [46] 100.0% [58] 1854(2.0%) 0(0.0%) for-loop ...terface-bugs/src/core/egraph.rkt:219:8 hash-update! [65] 100.0% -------------------------------------------------------------------------------- coerce-contract [50] 100.0% [59] 1050(1.2%) 0(0.0%) coerce-contract/f ...ntract/private/guts.rkt:373:0 coerce-simple-value [66] 100.0% -------------------------------------------------------------------------------- ival-mult [51] 100.0% [60] 772(0.8%) 0(0.0%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:821:4 ??? [68] 67.1% new-mpfr [67] 32.9% -------------------------------------------------------------------------------- loop [53] 100.0% [61] 600(0.7%) 600(0.7%) loop (unknown source) -------------------------------------------------------------------------------- loop [53] 100.0% [62] 310(0.3%) 310(0.3%) member (unknown source) -------------------------------------------------------------------------------- for-loop [47] 100.0% [63] 298(0.3%) 0(0.0%) ??? ...fix-interface-bugs/src/core/ematch.rkt:43:5 for-loop [69] 100.0% -------------------------------------------------------------------------------- custom-set-union [57] 100.0% [64] 11724(12.9%) 5132(5.6%) for-loop ...cts/racket/private/set-types.rkt:174:3 for-loop [70] 56.2% -------------------------------------------------------------------------------- loop [15] 5.8% for-loop [58] 38.0% for-loop [46] 56.2% [65] 4882(5.4%) 282(0.3%) hash-update! ...cket/private/more-scheme.rkt:365:4 ??? [71] 94.2% -------------------------------------------------------------------------------- coerce-contract/f [59] 100.0% [66] 1050(1.2%) 1050(1.2%) coerce-simple-value ...ract/private/guts.rkt:386:0 -------------------------------------------------------------------------------- bfmul [60] 31.9% bf [49] 68.1% [67] 796(0.9%) 796(0.9%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 -------------------------------------------------------------------------------- bf [49] 34.3% bfmul [60] 65.7% [68] 788(0.9%) 788(0.9%) ??? /opt/racket-7.2/collects/ffi/unsafe.rkt:905:4 -------------------------------------------------------------------------------- ??? [63] 100.0% [69] 298(0.3%) 0(0.0%) for-loop ...nterface-bugs/src/core/ematch.rkt:44:7 constant? [72] 100.0% -------------------------------------------------------------------------------- for-loop [64] 100.0% [70] 6592(7.2%) 6592(7.2%) for-loop ...cts/racket/private/set-types.rkt:178:5 -------------------------------------------------------------------------------- hash-update! [65] 100.0% [71] 4600(5.0%) 278(0.3%) ??? ...x-interface-bugs/src/core/egraph.rkt:221:24 for-loop [73] 94.0% -------------------------------------------------------------------------------- for-loop [69] 47.2% match-e [23] 52.8% [72] 632(0.7%) 298(0.3%) constant? ...face-bugs/src/syntax/syntax.rkt:726:0 value? [74] 52.8% -------------------------------------------------------------------------------- ??? [71] 100.0% [73] 4322(4.7%) 4024(4.4%) for-loop ...erface-bugs/src/core/egraph.rkt:222:26 update-en-expr [75] 6.9% -------------------------------------------------------------------------------- variable? [41] 46.8% constant? [72] 53.2% [74] 628(0.7%) 628(0.7%) value? ...interface-bugs/src/syntax/types.rkt:18:0 -------------------------------------------------------------------------------- for-loop [73] 49.3% loop! [28] 50.7% [75] 604(0.7%) 604(0.7%) update-en-expr ...e-bugs/src/core/egraph.rkt:207:0 --------------------------------------------------------------------------------