Profiling results ----------------- Total cpu time observed: 70702ms (out of 70860ms) Number of samples taken: 221 (once every 320ms) (Hiding functions with self<1.0% and local<2.0%: 2 of 95 hidden) ================================================================================ Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ================================================================================ ??? [7] 100.0% [1] 70702(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [4] 100.0% -------------------------------------------------------------------------------- ??? [7] 100.0% [2] 65384(92.5%) 0(0.0%) run-improve47 ...-nightlies/src/mainloop.rkt:346:0 simplify! [5] 100.0% -------------------------------------------------------------------------------- ??? [7] 100.0% [3] 316(0.4%) 0(0.0%) oracle-error ...bad-nightlies/src/points.rkt:188:0 for-loop [6] 100.0% -------------------------------------------------------------------------------- profile-thunk16 [1] 100.0% [4] 70702(100.0%) 0(0.0%) run ...et-7.5/share/pkgs/profile-lib/main.rkt:39:2 ??? [7] 100.0% -------------------------------------------------------------------------------- run-improve47 [2] 100.0% [5] 65384(92.5%) 0(0.0%) simplify! ...-bad-nightlies/src/mainloop.rkt:224:0 ??? [8] 100.0% -------------------------------------------------------------------------------- oracle-error [3] 100.0% [6] 316(0.4%) 0(0.0%) for-loop ...how-bad-nightlies/src/points.rkt:189:2 loop [41] 100.0% -------------------------------------------------------------------------------- run [4] 50.0% [7] 70702(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 50.0% run-improve47 [2] 46.2% prepare-points-intervals [10] 3.5% oracle-error [3] 0.2% -------------------------------------------------------------------------------- simplify! [5] 100.0% [8] 65384(92.5%) 0(0.0%) ??? ...ow-bad-nightlies/src/core/simplify.rkt:31:0 for-loop [9] 100.0% -------------------------------------------------------------------------------- ??? [8] 100.0% [9] 65384(92.5%) 0(0.0%) for-loop ...d-nightlies/src/core/simplify.rkt:51:2 ??? [11] 95.7% for-loop [13] 3.9% loop [15] 0.4% -------------------------------------------------------------------------------- ??? [7] 100.0% [10] 5002(7.1%) 0(0.0%) prepare-points-intervals ...s/src/points.rkt:110:0 loop [12] 93.8% eval-prog [14] 6.2% -------------------------------------------------------------------------------- for-loop [9] 100.0% [11] 62572(88.5%) 0(0.0%) ??? ...ow-bad-nightlies/src/core/simplify.rkt:92:0 for-loop [16] 87.5% find-matches [17] 12.5% -------------------------------------------------------------------------------- prepare-points-intervals [10] 100.0% [12] 4692(6.6%) 298(0.4%) loop ...ie/show-bad-nightlies/src/points.rkt:123:4 ??? [19] 53.8% loop [20] 39.8% -------------------------------------------------------------------------------- for-loop [9] 100.0% [13] 2576(3.6%) 0(0.0%) for-loop ...d-nightlies/src/core/simplify.rkt:59:4 prune-phase [18] 100.0% -------------------------------------------------------------------------------- prepare-points-intervals [10] 100.0% [14] 310(0.4%) 0(0.0%) eval-prog ...w-bad-nightlies/src/programs.rkt:83:0 temp85_0 [21] 100.0% -------------------------------------------------------------------------------- for-loop [9] 100.0% [15] 236(0.3%) 0(0.0%) loop ...bad-nightlies/src/core/extraction.rkt:25:2 for-loop [22] 100.0% -------------------------------------------------------------------------------- ??? [11] 100.0% [16] 54736(77.4%) 0(0.0%) for-loop ...d-nightlies/src/core/simplify.rkt:93:2 for-loop [23] 100.0% -------------------------------------------------------------------------------- ??? [11] 100.0% [17] 7836(11.1%) 0(0.0%) find-matches ...ghtlies/src/core/simplify.rkt:84:0 for-loop [24] 100.0% -------------------------------------------------------------------------------- for-loop [13] 100.0% [18] 2576(3.6%) 0(0.0%) prune-phase ...ghtlies/src/core/simplify.rkt:106:0 for-loop [25] 100.0% -------------------------------------------------------------------------------- loop [12] 100.0% [19] 2526(3.6%) 0(0.0%) ??? ...bie/show-bad-nightlies/src/points.rkt:106:2 ??? [59] 100.0% -------------------------------------------------------------------------------- loop [12] 100.0% [20] 1868(2.6%) 0(0.0%) loop ...bie/show-bad-nightlies/src/points.rkt:81:2 ??? [51] 100.0% -------------------------------------------------------------------------------- eval-prog [14] 100.0% [21] 310(0.4%) 0(0.0%) temp85_0 (unknown source) compile15 [26] 100.0% -------------------------------------------------------------------------------- loop [15] 100.0% [22] 236(0.3%) 0(0.0%) for-loop ...nightlies/src/core/extraction.rkt:29:4 for-loop [27] 100.0% -------------------------------------------------------------------------------- for-loop [16] 100.0% [23] 54736(77.4%) 324(0.5%) for-loop ...d-nightlies/src/core/simplify.rkt:96:4 merge-egraph-nodes! [28] 78.7% dedup-children! [40] 16.2% mk-enode-rec! [53] 2.3% pattern-substitute [30] 2.1% -------------------------------------------------------------------------------- for-loop [24] 50.0% find-matches [17] 50.0% [24] 7836(11.1%) 0(0.0%) for-loop ...d-nightlies/src/core/simplify.rkt:86:8 for-loop [24] 50.0% match-e [29] 50.0% -------------------------------------------------------------------------------- prune-phase [18] 100.0% [25] 2576(3.6%) 0(0.0%) for-loop ...-nightlies/src/core/simplify.rkt:107:2 update-leader! [33] 100.0% -------------------------------------------------------------------------------- temp85_0 [21] 100.0% [26] 310(0.4%) 0(0.0%) compile15 (unknown source) temp91_0 [31] 100.0% -------------------------------------------------------------------------------- for-loop [22] 100.0% [27] 236(0.3%) 0(0.0%) for-loop ...ightlies/src/core/extraction.rkt:37:16 for-loop [32] 100.0% -------------------------------------------------------------------------------- for-loop [35] 2.2% merge-egraph-nodes! [28] 4.4% for-loop [23] 93.4% [28] 43086(60.9%) 0(0.0%) merge-egraph-nodes! ...s/src/core/egraph.rkt:145:0 update-leader! [33] 62.3% loop! [49] 27.2% merge-egraph-nodes! [28] 4.4% dedup-children! [40] 3.1% for-loop [35] 3.0% -------------------------------------------------------------------------------- for-loop [42] 35.0% for-loop [24] 65.0% [29] 7836(11.1%) 932(1.3%) match-e ...-bad-nightlies/src/core/ematch.rkt:39:0 for-loop [34] 75.8% constant? [70] 5.1% variable? [36] 4.0% ??? [43] 3.2% -------------------------------------------------------------------------------- pattern-substitute [30] 23.1% for-loop [23] 76.9% [30] 1176(1.7%) 880(1.2%) pattern-substitute ...es/src/core/matcher.rkt:46:0 pattern-substitute [30] 23.1% ??? [38] 12.6% -------------------------------------------------------------------------------- compile15 [26] 100.0% [31] 310(0.4%) 0(0.0%) temp91_0 (unknown source) temp118_0 [37] 100.0% -------------------------------------------------------------------------------- for-loop [27] 100.0% [32] 236(0.3%) 236(0.3%) for-loop ...ightlies/src/core/extraction.rkt:41:23 -------------------------------------------------------------------------------- for-loop [25] 8.4% merge-egraph-nodes! [28] 91.6% [33] 30804(43.6%) 290(0.4%) update-leader! ...htlies/src/core/egraph.rkt:213:0 for-loop [39] 98.0% -------------------------------------------------------------------------------- match-e [29] 100.0% [34] 6698(9.5%) 2156(3.0%) for-loop ...ad-nightlies/src/core/ematch.rkt:52:11 for-loop [42] 65.8% procedure-reduce-keyword-arity-mask [44] 6.6% loop [41] 4.2% foldr [45] 2.5% -------------------------------------------------------------------------------- merge-egraph-nodes! [28] 100.0% [35] 2270(3.2%) 0(0.0%) for-loop ...ad-nightlies/src/core/egraph.rkt:199:4 merge-egraph-nodes! [28] 84.2% dedup-children! [40] 15.8% -------------------------------------------------------------------------------- match-e [29] 100.0% [36] 944(1.3%) 0(0.0%) variable? ...nightlies/src/syntax/syntax.rkt:729:0 value? [78] 68.2% ??? [43] 31.8% -------------------------------------------------------------------------------- temp91_0 [31] 100.0% [37] 310(0.4%) 0(0.0%) temp118_0 (unknown source) expand-capturing-lifts [46] 100.0% -------------------------------------------------------------------------------- pattern-substitute [30] 100.0% [38] 296(0.4%) 0(0.0%) ??? ...ects/racket/contract/private/guts.rkt:770:8 dict? [47] 100.0% -------------------------------------------------------------------------------- update-leader! [33] 100.0% [39] 30180(42.7%) 334(0.5%) for-loop ...ad-nightlies/src/core/egraph.rkt:218:6 for-loop [48] 53.4% hash-update! [57] 45.5% -------------------------------------------------------------------------------- for-loop [35] 3.3% merge-egraph-nodes! [28] 13.7% for-loop [23] 82.9% [40] 10692(15.1%) 0(0.0%) dedup-children! ...htlies/src/core/enode.rkt:202:0 loop [50] 85.0% loop [52] 15.0% -------------------------------------------------------------------------------- for-loop [34] 1.0% for-loop [6] 4.4% mk-enode-rec! [53] 6.5% loop! [49] 16.8% loop [41] 71.3% [41] 7252(10.3%) 0(0.0%) loop ...-7.5/collects/racket/private/map.rkt:40:19 loop [41] 71.3% loop! [49] 16.8% mk-enode-rec! [53] 6.5% ??? [51] 4.4% -------------------------------------------------------------------------------- for-loop [34] 100.0% [42] 5676(8.0%) 0(0.0%) for-loop ...ad-nightlies/src/core/ematch.rkt:58:37 match-e [29] 84.1% ??? [54] 15.9% -------------------------------------------------------------------------------- variable? [36] 33.3% match-e [29] 66.7% [43] 902(1.3%) 902(1.3%) ??? ...contract/private/arrow-val-first.rkt:486:18 -------------------------------------------------------------------------------- for-loop [34] 100.0% [44] 890(1.3%) 890(1.3%) procedure-reduce-keyword-arity-mask ....rkt:1597:4 -------------------------------------------------------------------------------- for-loop [34] 100.0% [45] 336(0.5%) 0(0.0%) foldr ....5/collects/racket/private/list.rkt:242:4 cp-2 [55] 100.0% -------------------------------------------------------------------------------- temp118_0 [37] 100.0% [46] 310(0.4%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [56] 100.0% -------------------------------------------------------------------------------- ??? [38] 100.0% [47] 296(0.4%) 296(0.4%) dict? ...ollects/racket/private/generic.rkt:146:11 -------------------------------------------------------------------------------- for-loop [39] 100.0% [48] 16104(22.8%) 302(0.4%) for-loop ...ad-nightlies/src/core/egraph.rkt:219:8 hash-update! [57] 98.1% -------------------------------------------------------------------------------- loop [41] 23.6% merge-egraph-nodes! [28] 76.4% [49] 13034(18.4%) 3132(4.4%) loop! ...ow-bad-nightlies/src/core/enode.rkt:194:2 custom-set-union [58] 53.1% loop [41] 23.6% set [60] 6.9% update-en-expr [85] 2.4% -------------------------------------------------------------------------------- dedup-children! [40] 11.5% loop [50] 88.5% [50] 9084(12.8%) 9084(12.8%) loop ...racket-7.5/collects/racket/list.rkt:434:17 loop [50] 88.5% -------------------------------------------------------------------------------- loop [41] 14.5% loop [20] 85.5% [51] 2184(3.1%) 300(0.4%) ??? ...e/show-bad-nightlies/src/programs.rkt:115:2 ??? [59] 86.3% -------------------------------------------------------------------------------- dedup-children! [40] 15.5% loop [52] 84.5% [52] 1608(2.3%) 0(0.0%) loop ...racket-7.5/collects/racket/list.rkt:417:34 loop [52] 84.5% member [61] 11.0% loop [62] 4.5% -------------------------------------------------------------------------------- loop [41] 24.0% for-loop [23] 76.0% [53] 1282(1.8%) 0(0.0%) mk-enode-rec! ...ghtlies/src/core/egraph.rkt:117:0 do-curry [64] 36.8% hash-has-key? [65] 28.1% loop [41] 24.0% new-enode [66] 11.1% -------------------------------------------------------------------------------- for-loop [42] 100.0% [54] 902(1.3%) 0(0.0%) ??? ...show-bad-nightlies/src/core/ematch.rkt:43:5 for-loop [63] 100.0% -------------------------------------------------------------------------------- foldr [45] 100.0% [55] 336(0.5%) 336(0.5%) cp-2 .../racket-7.5/collects/racket/list.rkt:826:2 -------------------------------------------------------------------------------- expand-capturing-lifts [46] 100.0% [56] 310(0.4%) 0(0.0%) lambda-clause-expander (unknown source) loop [62] 100.0% -------------------------------------------------------------------------------- for-loop [39] 46.5% for-loop [48] 53.5% [57] 29544(41.8%) 0(0.0%) hash-update! ...cket/private/more-scheme.rkt:365:4 ??? [67] 100.0% -------------------------------------------------------------------------------- loop! [49] 100.0% [58] 7484(10.6%) 2374(3.4%) custom-set-union ...et/private/set-types.rkt:168:0 for-loop [68] 63.8% for-loop [73] 4.5% -------------------------------------------------------------------------------- ??? [51] 42.7% ??? [19] 57.3% [59] 4410(6.2%) 646(0.9%) ??? ...tract/private/arrow-higher-order.rkt:375:33 sample-multi-bounded [69] 49.8% ival-mult [71] 21.4% mk-ival [72] 14.1% -------------------------------------------------------------------------------- loop! [49] 100.0% [60] 1788(2.5%) 1788(2.5%) set ...collects/racket/private/set-types.rkt:992:0 -------------------------------------------------------------------------------- loop [52] 100.0% [61] 966(1.4%) 966(1.4%) member (unknown source) -------------------------------------------------------------------------------- lambda-clause-expander [56] 32.6% loop [52] 67.4% [62] 952(1.3%) 642(0.9%) loop (unknown source) expand-id-application-form17 [74] 32.6% -------------------------------------------------------------------------------- ??? [54] 100.0% [63] 902(1.3%) 602(0.9%) for-loop ...bad-nightlies/src/core/ematch.rkt:44:7 constant? [70] 33.3% -------------------------------------------------------------------------------- mk-enode-rec! [53] 100.0% [64] 638(0.9%) 638(0.9%) do-curry ...-7.5/collects/racket/function.rkt:71:2 -------------------------------------------------------------------------------- mk-enode-rec! [53] 100.0% [65] 360(0.5%) 360(0.5%) hash-has-key? ...ket/private/more-scheme.rkt:371:2 -------------------------------------------------------------------------------- mk-enode-rec! [53] 100.0% [66] 284(0.4%) 0(0.0%) new-enode ...ad-nightlies/src/core/enode.rkt:101:0 type-of-enode-expr [75] 100.0% -------------------------------------------------------------------------------- hash-update! [57] 100.0% [67] 29544(41.8%) 934(1.3%) ??? ...ow-bad-nightlies/src/core/egraph.rkt:221:24 for-loop [76] 96.8% -------------------------------------------------------------------------------- custom-set-union [58] 100.0% [68] 4774(6.8%) 1838(2.6%) for-loop ...cts/racket/private/set-types.rkt:174:3 for-loop [77] 61.5% -------------------------------------------------------------------------------- ??? [59] 100.0% [69] 2196(3.1%) 610(0.9%) sample-multi-bounded ...htlies/src/points.rkt:15:0 random-ranges [80] 40.6% for-loop [82] 16.4% return/no-unsupplied [83] 15.2% -------------------------------------------------------------------------------- for-loop [63] 27.4% match-e [29] 72.6% [70] 1094(1.5%) 0(0.0%) constant? ...nightlies/src/syntax/syntax.rkt:726:0 value? [78] 100.0% -------------------------------------------------------------------------------- ??? [59] 100.0% [71] 944(1.3%) 0(0.0%) ival-mult ...d-nightlies/src/biginterval.rkt:119:0 bfmul [79] 100.0% -------------------------------------------------------------------------------- ??? [59] 100.0% [72] 624(0.9%) 0(0.0%) mk-ival ...-bad-nightlies/src/biginterval.rkt:65:0 bf [81] 100.0% -------------------------------------------------------------------------------- custom-set-union [58] 100.0% [73] 336(0.5%) 336(0.5%) for-loop ...cts/racket/private/set-types.rkt:152:2 -------------------------------------------------------------------------------- loop [62] 100.0% [74] 310(0.4%) 0(0.0%) expand-id-application-form17 (unknown source) resolve+shift28 [84] 100.0% -------------------------------------------------------------------------------- new-enode [66] 100.0% [75] 284(0.4%) 0(0.0%) type-of-enode-expr ...lies/src/core/enode.rkt:66:0 value? [78] 100.0% -------------------------------------------------------------------------------- ??? [67] 100.0% [76] 28610(40.5%) 26860(38.0%) for-loop ...d-nightlies/src/core/egraph.rkt:222:26 update-en-expr [85] 6.1% -------------------------------------------------------------------------------- for-loop [68] 100.0% [77] 2936(4.2%) 2936(4.2%) for-loop ...cts/racket/private/set-types.rkt:178:5 -------------------------------------------------------------------------------- type-of-enode-expr [75] 14.0% variable? [36] 31.8% constant? [70] 54.1% [78] 2022(2.9%) 2022(2.9%) value? ...-bad-nightlies/src/syntax/types.rkt:18:0 -------------------------------------------------------------------------------- ival-mult [71] 100.0% [79] 944(1.3%) 0(0.0%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:835:4 ??? [86] 68.2% new-mpfr [89] 31.8% -------------------------------------------------------------------------------- sample-multi-bounded [69] 100.0% [80] 892(1.3%) 298(0.4%) random-ranges ...ad-nightlies/src/common.rkt:171:0 build--> [90] 33.4% cons/c [91] 33.2% -------------------------------------------------------------------------------- mk-ival [72] 100.0% [81] 624(0.9%) 324(0.5%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:615:2 ??? [86] 48.1% -------------------------------------------------------------------------------- sample-multi-bounded [69] 100.0% [82] 360(0.5%) 360(0.5%) for-loop ...show-bad-nightlies/src/points.rkt:23:4 -------------------------------------------------------------------------------- sample-multi-bounded [69] 100.0% [83] 334(0.5%) 0(0.0%) return/no-unsupplied ...ct/private/arr-i.rkt:566:0 filter [87] 100.0% -------------------------------------------------------------------------------- expand-id-application-form17 [74] 100.0% [84] 310(0.4%) 0(0.0%) resolve+shift28 (unknown source) fallback-loop [88] 100.0% -------------------------------------------------------------------------------- loop! [49] 26.5% for-loop [76] 73.5% [85] 2380(3.4%) 2380(3.4%) update-en-expr ...htlies/src/core/egraph.rkt:207:0 -------------------------------------------------------------------------------- bf [81] 31.8% bfmul [79] 68.2% [86] 944(1.3%) 944(1.3%) ??? /opt/racket-7.5/collects/ffi/unsafe.rkt:938:4 -------------------------------------------------------------------------------- return/no-unsupplied [83] 100.0% [87] 334(0.5%) 334(0.5%) filter ...5/collects/racket/private/list.rkt:256:2 -------------------------------------------------------------------------------- resolve+shift28 [84] 100.0% [88] 310(0.4%) 0(0.0%) fallback-loop (unknown source) for-loop [92] 100.0% -------------------------------------------------------------------------------- bfmul [79] 100.0% [89] 300(0.4%) 300(0.4%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:348:0 -------------------------------------------------------------------------------- random-ranges [80] 100.0% [90] 298(0.4%) 298(0.4%) build--> ...act/private/arrow-val-first.rkt:1214:0 -------------------------------------------------------------------------------- random-ranges [80] 100.0% [91] 296(0.4%) 0(0.0%) cons/c .../racket/contract/private/guts.rkt:500:15 coerce-contract [93] 100.0% -------------------------------------------------------------------------------- fallback-loop [88] 100.0% [92] 310(0.4%) 310(0.4%) for-loop (unknown source) -------------------------------------------------------------------------------- cons/c [91] 100.0% [93] 296(0.4%) 296(0.4%) coerce-contract ...contract/private/guts.rkt:307:0 --------------------------------------------------------------------------------