Profiling results ----------------- Total cpu time observed: 70622ms (out of 71260ms) Number of samples taken: 195 (once every 362ms) (Hiding functions with self<1.0% and local<2.0%: 2 of 66 hidden) ======================================================================= Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ======================================================================= run [6] 50.0% [1] 70622(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [2] 50.0% prepare-points-intervals [7] 45.6% run-improve40 [3] 2.0% oracle-error [4] 1.9% errors [5] 0.5% ----------------------------------------------------------------------- ??? [1] 100.0% [2] 70622(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [6] 100.0% ----------------------------------------------------------------------- ??? [1] 100.0% [3] 2866(4.1%) 0(0.0%) run-improve40 ...xperiments/src/mainloop.rkt:340:0 prepare-points-intervals [7] 64.9% simplify! [9] 24.6% for-loop [11] 10.5% ----------------------------------------------------------------------- ??? [1] 100.0% [4] 2662(3.8%) 0(0.0%) oracle-error ...g-experiments/src/points.rkt:212:0 for-loop [8] 100.0% ----------------------------------------------------------------------- ??? [1] 100.0% [5] 664(0.9%) 0(0.0%) errors ...pruning-experiments/src/points.rkt:228:0 for-loop [10] 100.0% ----------------------------------------------------------------------- profile-thunk16 [2] 100.0% [6] 70622(100.0%) 0(0.0%) run ...et-7.0/share/pkgs/profile-lib/main.rkt:39:2 ??? [1] 100.0% ----------------------------------------------------------------------- run-improve40 [3] 2.8% ??? [1] 97.2% [7] 66290(93.9%) 0(0.0%) prepare-points-intervals ...s/src/points.rkt:133:0 loop [12] 100.0% ----------------------------------------------------------------------- oracle-error [4] 100.0% [8] 2662(3.8%) 0(0.0%) for-loop ...uning-experiments/src/points.rkt:213:2 loop [29] 100.0% ----------------------------------------------------------------------- run-improve40 [3] 100.0% [9] 706(1.0%) 0(0.0%) simplify! ...ng-experiments/src/mainloop.rkt:217:0 ??? [13] 100.0% ----------------------------------------------------------------------- errors [5] 100.0% [10] 664(0.9%) 0(0.0%) for-loop ...uning-experiments/src/points.rkt:230:2 ??? [31] 100.0% ----------------------------------------------------------------------- run-improve40 [3] 100.0% [11] 300(0.4%) 0(0.0%) for-loop ...ing-experiments/src/mainloop.rkt:355:4 run-iter! [14] 100.0% ----------------------------------------------------------------------- prepare-points-intervals [7] 100.0% [12] 66290(93.9%) 0(0.0%) loop ...e/pruning-experiments/src/points.rkt:145:4 loop [15] 97.0% ??? [16] 3.0% ----------------------------------------------------------------------- simplify! [9] 100.0% [13] 706(1.0%) 0(0.0%) ??? ...ning-experiments/src/core/simplify.rkt:29:0 loop [17] 100.0% ----------------------------------------------------------------------- for-loop [11] 100.0% [14] 300(0.4%) 0(0.0%) run-iter! ...ng-experiments/src/mainloop.rkt:321:0 localize! [18] 100.0% ----------------------------------------------------------------------- loop [12] 100.0% [15] 64324(91.1%) 610(0.9%) loop ...e/pruning-experiments/src/points.rkt:104:2 ??? [31] 90.9% ??? [34] 3.7% log! [19] 3.4% ----------------------------------------------------------------------- loop [12] 100.0% [16] 1966(2.8%) 404(0.6%) ??? ...ie/pruning-experiments/src/points.rkt:129:2 ??? [34] 48.6% compose [21] 30.8% ----------------------------------------------------------------------- ??? [13] 100.0% [17] 706(1.0%) 0(0.0%) loop ...ing-experiments/src/core/simplify.rkt:44:2 one-iter [20] 100.0% ----------------------------------------------------------------------- run-iter! [14] 100.0% [18] 300(0.4%) 0(0.0%) localize! ...ng-experiments/src/mainloop.rkt:120:0 localize-error [22] 100.0% ----------------------------------------------------------------------- loop [15] 100.0% [19] 2182(3.1%) 600(0.8%) log! ...ie/pruning-experiments/src/points.rkt:85:2 for-loop [23] 41.7% ??? [33] 15.6% warn21 [25] 15.2% ----------------------------------------------------------------------- loop [17] 100.0% [20] 706(1.0%) 0(0.0%) one-iter ...xperiments/src/core/simplify.rkt:100:0 find-matches [24] 100.0% ----------------------------------------------------------------------- ??? [16] 100.0% [21] 606(0.9%) 606(0.9%) compose ...collects/racket/private/list.rkt:385:12 ----------------------------------------------------------------------- localize! [18] 100.0% [22] 300(0.4%) 0(0.0%) localize-error ...ments/src/core/localize.rkt:52:0 hash-ref! [26] 100.0% ----------------------------------------------------------------------- log! [19] 100.0% [23] 910(1.3%) 910(1.3%) for-loop ...uning-experiments/src/points.rkt:92:23 ----------------------------------------------------------------------- one-iter [20] 100.0% [24] 706(1.0%) 0(0.0%) find-matches ...riments/src/core/simplify.rkt:64:0 for-loop [27] 100.0% ----------------------------------------------------------------------- log! [19] 100.0% [25] 332(0.5%) 332(0.5%) warn21 .../pruning-experiments/src/errors.rkt:63:0 ----------------------------------------------------------------------- loop [29] 50.0% localize-error [22] 50.0% [26] 300(0.4%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 parse-loop15 [28] 100.0% ----------------------------------------------------------------------- for-loop [27] 50.0% find-matches [24] 50.0% [27] 706(1.0%) 350(0.5%) for-loop ...experiments/src/core/simplify.rkt:66:8 for-loop [27] 50.0% match-e [30] 25.2% ----------------------------------------------------------------------- hash-ref! [26] 100.0% [28] 300(0.4%) 0(0.0%) parse-loop15 ...s/racket/match/compiler.rkt:418:15 loop [29] 100.0% ----------------------------------------------------------------------- parse-loop15 [28] 0.3% loop [29] 9.8% for-loop [8] 89.9% [29] 2962(4.2%) 0(0.0%) loop ...-7.0/collects/racket/private/map.rkt:40:19 ??? [31] 89.9% loop [29] 9.8% hash-ref! [26] 0.2% ??? [57] 0.2% ----------------------------------------------------------------------- for-loop [27] 100.0% [30] 356(0.5%) 356(0.5%) match-e ...ng-experiments/src/core/ematch.rkt:39:0 ----------------------------------------------------------------------- for-loop [10] 1.1% loop [29] 4.3% loop [15] 94.6% [31] 61818(87.5%) 330(0.5%) ??? .../pruning-experiments/src/programs.rkt:146:2 ??? [32] 91.6% ??? [34] 7.9% ----------------------------------------------------------------------- ??? [31] 100.0% [32] 56616(80.2%) 634(0.9%) ??? ...t-7.0/collects/racket/private/kw.rkt:592:14 ??? [33] 75.3% ??? [34] 20.3% ??? [35] 3.3% ----------------------------------------------------------------------- log! [19] 0.8% fold-binary-pred [49] 4.6% ??? [32] 94.6% [33] 43548(61.7%) 3992(5.7%) ??? ...tract/private/arrow-higher-order.rkt:361:33 ival-fmod [36] 68.4% ival-sinh [38] 12.8% ival-sub [39] 12.0% ??? [43] 0.8% ----------------------------------------------------------------------- ??? [16] 4.8% loop [15] 12.2% ??? [31] 24.7% ??? [32] 58.3% [34] 19726(27.9%) 1258(1.8%) ??? ...tract/private/arrow-higher-order.rkt:357:33 ival-pow [37] 55.3% mk-ival [40] 19.0% ->flonum [41] 17.9% ----------------------------------------------------------------------- ??? [32] 100.0% [35] 1864(2.6%) 694(1.0%) ??? ...ng-experiments/src/syntax/syntax.rkt:317:17 return/no-unsupplied [42] 62.8% ----------------------------------------------------------------------- ??? [33] 100.0% [36] 31194(44.2%) 4396(6.2%) ival-fmod ...experiments/src/biginterval.rkt:377:0 ival-div [44] 46.8% ival-fabs [45] 18.2% ??? [57] 8.7% ival-mult [47] 3.1% force [53] 2.9% ...higher-order.rkt:357:33 [50] 2.1% fold-binary-pred [49] 2.1% ...higher-order.rkt:361:33 [52] 2.0% ----------------------------------------------------------------------- ??? [34] 100.0% [37] 10902(15.4%) 1228(1.7%) ival-pow ...-experiments/src/biginterval.rkt:209:0 ??? [57] 80.6% fold-binary-pred [49] 5.4% ...higher-order.rkt:357:33 [50] 2.8% ----------------------------------------------------------------------- ??? [33] 100.0% [38] 5578(7.9%) 0(0.0%) ival-sinh ...experiments/src/biginterval.rkt:357:0 ??? [57] 100.0% ----------------------------------------------------------------------- ??? [33] 100.0% [39] 5244(7.4%) 1324(1.9%) ival-sub ...g-experiments/src/biginterval.rkt:93:0 ??? [57] 69.1% ...higher-order.rkt:361:33 [52] 5.7% ----------------------------------------------------------------------- ??? [34] 100.0% [40] 3740(5.3%) 0(0.0%) mk-ival ...ng-experiments/src/biginterval.rkt:59:0 bf [46] 100.0% ----------------------------------------------------------------------- ??? [34] 100.0% [41] 3530(5.0%) 3530(5.0%) ->flonum ...ning-experiments/src/programs.rkt:38:0 ----------------------------------------------------------------------- ??? [35] 100.0% [42] 1170(1.7%) 1170(1.7%) return/no-unsupplied ...ct/private/arr-i.rkt:534:0 ----------------------------------------------------------------------- ??? [33] 100.0% [43] 340(0.5%) 0(0.0%) ??? ...ects/racket/contract/private/prop.rkt:519:4 ??? [48] 100.0% ----------------------------------------------------------------------- ival-fmod [36] 100.0% [44] 14588(20.7%) 1592(2.3%) ival-div ...-experiments/src/biginterval.rkt:141:0 ??? [57] 67.9% classify-ival [51] 8.2% ...higher-order.rkt:357:33 [50] 4.6% fold-binary-pred [49] 4.3% force [53] 2.1% bf/ [56] 2.1% ----------------------------------------------------------------------- ival-fmod [36] 100.0% [45] 5678(8.0%) 946(1.3%) ival-fabs ...experiments/src/biginterval.rkt:349:0 ??? [57] 51.1% fold-binary-pred [49] 26.7% ...higher-order.rkt:361:33 [52] 5.5% ----------------------------------------------------------------------- mk-ival [40] 100.0% [46] 3740(5.3%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [63] 100.0% ----------------------------------------------------------------------- ival-fmod [36] 100.0% [47] 954(1.4%) 0(0.0%) ival-mult ...experiments/src/biginterval.rkt:105:0 bf* [54] 65.0% classify-ival [51] 35.0% ----------------------------------------------------------------------- ??? [43] 100.0% [48] 340(0.5%) 0(0.0%) ??? ...lects/racket/contract/private/orc.rkt:83:14 ??? [55] 100.0% ----------------------------------------------------------------------- ival-pow [37] 17.4% ival-div [44] 18.6% ival-fmod [36] 19.2% ival-fabs [45] 44.8% [49] 3390(4.8%) 0(0.0%) fold-binary-pred ...gfloat/bigfloat-mpfr.rkt:117:0 ??? [33] 100.0% ----------------------------------------------------------------------- ival-pow [37] 18.4% ival-fmod [36] 40.4% ival-div [44] 41.2% [50] 1628(2.3%) 1290(1.8%) ...higher-order.rkt:357:33 (unknown source) ??? [58] 20.8% ----------------------------------------------------------------------- ival-mult [47] 21.8% ival-div [44] 78.2% [51] 1530(2.2%) 1530(2.2%) classify-ival ...riments/src/biginterval.rkt:310:0 ----------------------------------------------------------------------- ival-sub [39] 24.0% ival-fabs [45] 25.0% ival-fmod [36] 51.0% [52] 1242(1.8%) 944(1.3%) ...higher-order.rkt:361:33 (unknown source) ??? [59] 24.0% ----------------------------------------------------------------------- ival-div [44] 24.7% ival-fmod [36] 75.3% [53] 1222(1.7%) 1222(1.7%) force ...collects/racket/private/promise.rkt:109:0 ----------------------------------------------------------------------- ival-mult [47] 100.0% [54] 620(0.9%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [57] 100.0% ----------------------------------------------------------------------- ??? [48] 100.0% [55] 340(0.5%) 340(0.5%) ??? ...ects/racket/contract/private/list.rkt:163:5 ----------------------------------------------------------------------- ival-div [44] 100.0% [56] 300(0.4%) 300(0.4%) bf/ ...th/private/bigfloat/bigfloat-mpfr.rkt:102:0 ----------------------------------------------------------------------- loop [29] 0.9% bf* [54] 1.8% ival-fmod [36] 7.9% ival-fabs [45] 8.4% ival-sub [39] 10.5% ival-sinh [38] 16.2% ival-pow [37] 25.5% ival-div [44] 28.8% [57] 34420(48.7%) 10574(15.0%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfdiv [60] 48.3% bfsinh [61] 13.1% bftruncate [62] 7.9% ----------------------------------------------------------------------- ...higher-order.rkt:357:33 [50]100.0% [58] 338(0.5%) 338(0.5%) ??? ...cts/racket/contract/private/list.rkt:209:10 ----------------------------------------------------------------------- ...higher-order.rkt:361:33 [52]100.0% [59] 298(0.4%) 298(0.4%) ??? ...ects/racket/contract/private/guts.rkt:746:8 ----------------------------------------------------------------------- ??? [57] 100.0% [60] 16622(23.5%) 648(0.9%) bfdiv ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [63] 55.1% ??? [64] 41.0% ----------------------------------------------------------------------- ??? [57] 100.0% [61] 4510(6.4%) 638(0.9%) bfsinh ...lib/math/private/bigfloat/mpfr.rkt:618:4 new-mpfr [63] 52.2% ??? [64] 33.6% ----------------------------------------------------------------------- ??? [57] 100.0% [62] 2714(3.8%) 0(0.0%) bftruncate ...math/private/bigfloat/mpfr.rkt:716:4 new-mpfr [63] 88.0% ??? [64] 12.0% ----------------------------------------------------------------------- bfsinh [61] 13.3% bftruncate [62] 13.5% bf [46] 21.2% bfdiv [60] 51.9% [63] 17648(25.0%) 17648(25.0%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ----------------------------------------------------------------------- bftruncate [62] 3.8% bfsinh [61] 17.5% bfdiv [60] 78.7% [64] 8652(12.3%) 8652(12.3%) ??? /opt/racket-7.0/collects/ffi/unsafe.rkt:898:4 -----------------------------------------------------------------------