Profiling results ----------------- Total cpu time observed: 525630ms (out of 526700ms) Number of samples taken: 3223 (once every 163ms) (Hiding functions with self<1.0% and local<2.0%: 8 of 109 hidden) =========================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee =========================================================================== ??? [5] 100.0% [1] 525630(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [3] 100.0% --------------------------------------------------------------------------- atab-add-altn [93] 14.8% ??? [5] 85.2% [2] 352(0.1%) 0(0.0%) errors ...bie/optional-posits/src/points.rkt:222:0 for-loop [4] 100.0% --------------------------------------------------------------------------- profile-thunk16 [1] 100.0% [3] 525630(100.0%) 0(0.0%) run ...et-7.0/share/pkgs/profile-lib/main.rkt:39:2 ??? [5] 100.0% --------------------------------------------------------------------------- errors [2] 100.0% [4] 352(0.1%) 0(0.0%) for-loop ...e/optional-posits/src/points.rkt:224:2 ??? [5] 100.0% --------------------------------------------------------------------------- for-loop [4] 0.0% run [3] 50.0% [5] 525630(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 50.0% run-improve40 [6] 49.9% prepare-points-intervals [7] 0.0% errors [2] 0.0% eval-errors [9] 0.0% errors-score [8] 0.0% oracle-error [10] 0.0% ??? [73] 0.0% ulp-difference [11] 0.0% ordinary-value? [20] 0.0% --------------------------------------------------------------------------- ??? [5] 100.0% [6] 524358(99.8%) 0(0.0%) run-improve40 ...nal-posits/src/mainloop.rkt:336:0 simplify! [12] 100.0% --------------------------------------------------------------------------- ??? [5] 100.0% [7] 518(0.1%) 0(0.0%) prepare-points-intervals ...s/src/points.rkt:130:0 loop [13] 100.0% --------------------------------------------------------------------------- ??? [5] 100.0% [8] 152(0.0%) 0(0.0%) errors-score ...tional-posits/src/points.rkt:214:0 loop [14] 100.0% --------------------------------------------------------------------------- ??? [5] 100.0% [9] 152(0.0%) 0(0.0%) eval-errors ...ptional-posits/src/points.rkt:197:0 for-loop [15] 100.0% --------------------------------------------------------------------------- ??? [5] 100.0% [10] 150(0.0%) 0(0.0%) oracle-error ...tional-posits/src/points.rkt:206:0 for-loop [16] 100.0% --------------------------------------------------------------------------- ??? [5] 100.0% [11] 100(0.0%) 52(0.0%) ulp-difference ...tional-posits/src/float.rkt:39:0 infer-double-representation [17] 48.0% --------------------------------------------------------------------------- run-improve40 [6] 100.0% [12] 524306(99.7%) 0(0.0%) simplify! ...ptional-posits/src/mainloop.rkt:213:0 ??? [18] 100.0% --------------------------------------------------------------------------- prepare-points-intervals [7] 100.0% [13] 518(0.1%) 0(0.0%) loop ...erbie/optional-posits/src/points.rkt:142:4 loop [19] 90.7% ??? [22] 9.3% --------------------------------------------------------------------------- errors-score [8] 100.0% [14] 152(0.0%) 0(0.0%) loop .../racket-7.0/collects/racket/list.rkt:559:2 ordinary-value? [20] 100.0% --------------------------------------------------------------------------- eval-errors [9] 100.0% [15] 152(0.0%) 50(0.0%) for-loop ...e/optional-posits/src/points.rkt:199:2 ??? [73] 34.2% ??? [86] 32.9% --------------------------------------------------------------------------- oracle-error [10] 100.0% [16] 150(0.0%) 0(0.0%) for-loop ...e/optional-posits/src/points.rkt:207:2 loop [60] 66.7% map [21] 33.3% --------------------------------------------------------------------------- ulp-difference [11] 100.0% [17] 48(0.0%) 0(0.0%) infer-double-representation .../src/float.rkt:33:0 for-loop [27] 100.0% --------------------------------------------------------------------------- simplify! [12] 100.0% [18] 524306(99.7%) 0(0.0%) ??? .../optional-posits/src/core/simplify.rkt:26:0 for-loop [23] 100.0% --------------------------------------------------------------------------- loop [13] 100.0% [19] 470(0.1%) 0(0.0%) loop ...erbie/optional-posits/src/points.rkt:101:2 ??? [73] 88.5% ??? [86] 11.5% --------------------------------------------------------------------------- ??? [5] 24.8% loop [14] 75.2% [20] 202(0.0%) 50(0.0%) ordinary-value? ...ional-posits/src/float.rkt:73:0 special-value? [24] 75.2% --------------------------------------------------------------------------- for-loop [16] 100.0% [21] 50(0.0%) 0(0.0%) map ...t-7.0/collects/racket/private/map.rkt:35:13 ??? [25] 100.0% --------------------------------------------------------------------------- loop [13] 100.0% [22] 48(0.0%) 0(0.0%) ??? ...herbie/optional-posits/src/points.rkt:126:2 compose [41] 100.0% --------------------------------------------------------------------------- ??? [18] 100.0% [23] 524306(99.7%) 52(0.0%) for-loop ...onal-posits/src/core/simplify.rkt:33:2 one-iter [26] 100.0% --------------------------------------------------------------------------- ordinary-value? [20] 100.0% [24] 152(0.0%) 0(0.0%) special-value? ...tional-posits/src/float.rkt:69:0 for-loop [27] 100.0% --------------------------------------------------------------------------- map [21] 100.0% [25] 50(0.0%) 0(0.0%) ??? ...erbie/optional-posits/src/points.rkt:208:26 ??? [73] 100.0% --------------------------------------------------------------------------- for-loop [23] 100.0% [26] 524254(99.7%) 280(0.1%) one-iter ...onal-posits/src/core/simplify.rkt:86:0 find-matches [28] 54.7% for-loop [29] 44.7% for-loop [30] 0.6% --------------------------------------------------------------------------- infer-double-representation [17] 24.0% special-value? [24] 76.0% [27] 200(0.0%) 150(0.0%) for-loop ...bie/optional-posits/src/float.rkt:16:3 ??? [97] 25.0% --------------------------------------------------------------------------- one-iter [26] 100.0% [28] 286852(54.6%) 0(0.0%) find-matches ...-posits/src/core/simplify.rkt:56:0 for-loop [31] 100.0% --------------------------------------------------------------------------- one-iter [26] 100.0% [29] 234144(44.5%) 336(0.1%) for-loop ...onal-posits/src/core/simplify.rkt:95:2 loop [32] 98.7% reduce-to-single! [35] 1.1% --------------------------------------------------------------------------- one-iter [26] 100.0% [30] 2978(0.6%) 222(0.0%) for-loop ...nal-posits/src/core/simplify.rkt:111:2 set-precompute! [33] 92.5% --------------------------------------------------------------------------- for-loop [31] 49.6% find-matches [28] 50.4% [31] 286852(54.6%) 28754(5.5%) for-loop ...onal-posits/src/core/simplify.rkt:58:8 for-loop [31] 49.6% match-e [37] 44.4% ??? [62] 0.6% variable? [51] 0.1% --------------------------------------------------------------------------- loop [32] 1.2% for-loop [29] 98.8% [32] 231154(44.0%) 0(0.0%) loop ...ptional-posits/src/core/simplify.rkt:100:4 apply-match [34] 98.8% loop [32] 1.2% reduce-to-single! [35] 0.0% --------------------------------------------------------------------------- for-loop [30] 100.0% [33] 2756(0.5%) 332(0.1%) set-precompute! ...its/src/core/simplify.rkt:117:0 for-loop [36] 88.0% --------------------------------------------------------------------------- loop [32] 100.0% [34] 231098(44.0%) 52(0.0%) apply-match ...l-posits/src/core/simplify.rkt:65:0 for-loop [38] 94.1% match-e [37] 5.3% set [61] 0.4% --------------------------------------------------------------------------- loop [32] 2.1% for-loop [29] 97.9% [35] 2710(0.5%) 0(0.0%) reduce-to-single! ...its/src/core/egraph.rkt:318:0 for-loop [39] 100.0% --------------------------------------------------------------------------- set-precompute! [33] 100.0% [36] 2424(0.5%) 470(0.1%) for-loop ...nal-posits/src/core/simplify.rkt:119:2 setfindf [40] 78.5% compose [41] 2.1% --------------------------------------------------------------------------- apply-match [34] 2.3% for-loop [49] 19.4% for-loop [31] 78.3% [37] 266768(50.8%) 24928(4.7%) match-e ...ptional-posits/src/core/ematch.rkt:39:0 constant? [77] 52.7% for-loop [42] 32.4% variable? [51] 4.4% make-sequence [45] 0.9% ??? [79] 0.8% --------------------------------------------------------------------------- apply-match [34] 100.0% [38] 217478(41.4%) 716(0.1%) for-loop ...onal-posits/src/core/simplify.rkt:78:2 merge-egraph-nodes! [43] 49.9% dedup-children! [48] 47.5% substitute-e [44] 1.8% mk-enode! [47] 0.4% --------------------------------------------------------------------------- reduce-to-single! [35] 100.0% [39] 2710(0.5%) 300(0.1%) for-loop ...ional-posits/src/core/egraph.rkt:319:8 constant? [77] 88.9% --------------------------------------------------------------------------- for-loop [36] 100.0% [40] 1904(0.4%) 104(0.0%) setfindf ...e/optional-posits/src/common.rkt:138:0 for-loop [46] 91.9% make-sequence [45] 2.6% --------------------------------------------------------------------------- ??? [22] 49.0% for-loop [36] 51.0% [41] 98(0.0%) 98(0.0%) compose ...collects/racket/private/list.rkt:385:12 --------------------------------------------------------------------------- match-e [37] 100.0% [42] 129526(24.6%) 22774(4.3%) for-loop ...ional-posits/src/core/ematch.rkt:52:11 for-loop [49] 80.6% curry* [56] 0.5% foldr [54] 0.4% cp-2 [64] 0.3% foldl [57] 0.3% --------------------------------------------------------------------------- merge-egraph-nodes! [43] 0.9% for-loop [38] 99.1% [43] 108580(20.7%) 0(0.0%) merge-egraph-nodes! ...s/src/core/egraph.rkt:143:0 loop! [50] 90.7% dedup-children! [48] 5.5% update-leader! [53] 2.7% merge-egraph-nodes! [43] 0.9% enode-merge! [58] 0.3% --------------------------------------------------------------------------- for-loop [52] 44.9% for-loop [38] 55.1% [44] 3872(0.7%) 0(0.0%) substitute-e ...al-posits/src/core/ematch.rkt:64:0 for-loop [52] 44.9% constant? [77] 34.3% variable? [51] 17.4% ??? [79] 3.3% --------------------------------------------------------------------------- setfindf [40] 1.9% match-e [37] 98.1% [45] 2684(0.5%) 2684(0.5%) make-sequence ...ects/racket/private/for.rkt:528:2 --------------------------------------------------------------------------- setfindf [40] 100.0% [46] 1750(0.3%) 304(0.1%) for-loop ...e/optional-posits/src/common.rkt:139:2 constant? [77] 79.8% ??? [79] 2.9% --------------------------------------------------------------------------- for-loop [38] 100.0% [47] 946(0.2%) 248(0.0%) mk-enode! ...ional-posits/src/core/egraph.rkt:97:0 hash-has-key? [55] 73.8% --------------------------------------------------------------------------- merge-egraph-nodes! [43] 5.4% for-loop [38] 94.6% [48] 109298(20.8%) 0(0.0%) dedup-children! ...posits/src/core/enode.rkt:198:0 loop [59] 99.8% loop [68] 0.2% --------------------------------------------------------------------------- for-loop [42] 100.0% [49] 106982(20.4%) 2248(0.4%) for-loop ...ional-posits/src/core/ematch.rkt:58:37 match-e [37] 91.1% ??? [62] 6.9% --------------------------------------------------------------------------- loop [60] 26.4% merge-egraph-nodes! [43] 73.6% [50] 99438(18.9%) 23568(4.5%) loop! .../optional-posits/src/core/enode.rkt:190:2 custom-set-union [70] 40.9% loop [60] 32.4% set [61] 12.6% --------------------------------------------------------------------------- for-loop [31] 1.1% substitute-e [44] 5.2% match-e [37] 93.7% [51] 27460(5.2%) 1600(0.3%) variable? ...al-posits/src/syntax/syntax.rkt:724:0 for-loop [89] 51.8% value? [90] 31.5% ??? [79] 10.9% --------------------------------------------------------------------------- substitute-e [44] 100.0% [52] 3212(0.6%) 0(0.0%) for-loop ...ional-posits/src/core/ematch.rkt:73:24 substitute-e [44] 100.0% --------------------------------------------------------------------------- merge-egraph-nodes! [43] 100.0% [53] 2896(0.6%) 0(0.0%) update-leader! ...posits/src/core/egraph.rkt:211:0 for-loop [63] 91.4% for-loop [69] 8.6% --------------------------------------------------------------------------- for-loop [42] 100.0% [54] 976(0.2%) 100(0.0%) foldr ....0/collects/racket/private/list.rkt:242:4 cp-2 [64] 89.8% --------------------------------------------------------------------------- mk-enode! [47] 100.0% [55] 698(0.1%) 698(0.1%) hash-has-key? ...ket/private/more-scheme.rkt:371:2 --------------------------------------------------------------------------- for-loop [42] 100.0% [56] 660(0.1%) 386(0.1%) curry* ...et-7.0/collects/racket/function.rkt:44:2 procedure-arity [67] 41.5% --------------------------------------------------------------------------- for-loop [42] 100.0% [57] 496(0.1%) 0(0.0%) foldl ....0/collects/racket/private/list.rkt:229:4 merge2 [65] 100.0% --------------------------------------------------------------------------- merge-egraph-nodes! [43] 100.0% [58] 312(0.1%) 0(0.0%) enode-merge! ...al-posits/src/core/enode.rkt:127:0 adopt-enode! [66] 100.0% --------------------------------------------------------------------------- dedup-children! [48] 4.6% loop [59] 95.4% [59] 109026(20.7%) 109026(20.7%) loop ...racket-7.0/collects/racket/list.rkt:434:17 loop [59] 95.4% --------------------------------------------------------------------------- for-loop [16] 0.2% loop! [50] 3.1% loop [60] 96.7% [60] 58546(11.1%) 6004(1.1%) loop ...-7.0/collects/racket/private/map.rkt:40:19 loop [60] 96.7% loop! [50] 2.8% ??? [73] 0.1% --------------------------------------------------------------------------- apply-match [34] 3.6% loop! [50] 96.4% [61] 25922(4.9%) 25194(4.8%) set ...collects/racket/private/set-types.rkt:981:0 for-loop [74] 2.8% --------------------------------------------------------------------------- for-loop [31] 30.5% for-loop [49] 69.5% [62] 10634(2.0%) 100(0.0%) ??? ...ie/optional-posits/src/core/ematch.rkt:43:5 for-loop [71] 99.1% --------------------------------------------------------------------------- update-leader! [53] 100.0% [63] 2646(0.5%) 0(0.0%) for-loop ...ional-posits/src/core/egraph.rkt:216:6 hash-update! [80] 52.3% for-loop [72] 47.7% --------------------------------------------------------------------------- for-loop [42] 32.2% foldr [54] 67.8% [64] 1292(0.2%) 712(0.1%) cp-2 .../racket-7.0/collects/racket/list.rkt:826:2 for-loop [75] 44.9% --------------------------------------------------------------------------- foldl [57] 100.0% [65] 496(0.1%) 496(0.1%) merge2 ...optional-posits/src/core/ematch.rkt:24:0 --------------------------------------------------------------------------- enode-merge! [58] 100.0% [66] 312(0.1%) 0(0.0%) adopt-enode! ...al-posits/src/core/enode.rkt:103:0 custom-set-union [70] 100.0% --------------------------------------------------------------------------- curry* [56] 100.0% [67] 274(0.1%) 274(0.1%) procedure-arity ...ket/private/norm-arity.rkt:7:27 --------------------------------------------------------------------------- dedup-children! [48] 8.3% loop [68] 91.7% [68] 272(0.1%) 0(0.0%) loop ...racket-7.0/collects/racket/list.rkt:417:34 loop [68] 91.7% loop [76] 8.3% --------------------------------------------------------------------------- update-leader! [53] 100.0% [69] 250(0.0%) 250(0.0%) for-loop ...ional-posits/src/core/egraph.rkt:226:6 --------------------------------------------------------------------------- adopt-enode! [66] 0.7% loop! [50] 99.3% [70] 42518(8.1%) 16344(3.1%) custom-set-union ...et/private/set-types.rkt:168:0 for-loop [78] 56.6% for-loop [81] 4.9% --------------------------------------------------------------------------- ??? [62] 100.0% [71] 10534(2.0%) 772(0.1%) for-loop ...tional-posits/src/core/ematch.rkt:44:7 constant? [77] 85.1% ??? [79] 7.6% --------------------------------------------------------------------------- for-loop [63] 100.0% [72] 1262(0.2%) 0(0.0%) for-loop ...ional-posits/src/core/egraph.rkt:217:8 hash-update! [80] 100.0% --------------------------------------------------------------------------- ??? [25] 6.5% loop [60] 6.5% for-loop [15] 6.8% ??? [5] 26.2% loop [19] 54.0% [73] 770(0.1%) 202(0.0%) ??? ...erbie/optional-posits/src/programs.rkt:99:2 ??? [82] 40.3% ??? [86] 19.7% ??? [85] 13.8% --------------------------------------------------------------------------- set [61] 100.0% [74] 728(0.1%) 728(0.1%) for-loop ...cts/racket/private/set-types.rkt:937:4 --------------------------------------------------------------------------- cp-2 [64] 100.0% [75] 580(0.1%) 580(0.1%) for-loop ...ket-7.0/collects/racket/list.rkt:827:4 --------------------------------------------------------------------------- loop [68] 100.0% [76] 272(0.1%) 272(0.1%) loop (unknown source) --------------------------------------------------------------------------- for-loop [46] 0.8% substitute-e [44] 1.2% for-loop [39] 1.3% for-loop [71] 4.8% match-e [37] 91.9% [77] 185514(35.3%) 14312(2.7%) constant? ...al-posits/src/syntax/syntax.rkt:721:0 for-loop [89] 57.8% value? [90] 34.5% --------------------------------------------------------------------------- custom-set-union [70] 100.0% [78] 24072(4.6%) 11204(2.1%) for-loop ...cts/racket/private/set-types.rkt:174:3 for-loop [83] 53.5% --------------------------------------------------------------------------- for-loop [46] 0.6% substitute-e [44] 2.9% for-loop [71] 9.0% variable? [51] 33.9% match-e [37] 53.0% [79] 8826(1.7%) 8724(1.7%) ??? ...contract/private/arrow-val-first.rkt:388:18 for-loop [87] 0.6% ??? [86] 0.6% --------------------------------------------------------------------------- for-loop [72] 47.7% for-loop [63] 52.3% [80] 2646(0.5%) 0(0.0%) hash-update! ...cket/private/more-scheme.rkt:365:4 ??? [84] 100.0% --------------------------------------------------------------------------- custom-set-union [70] 100.0% [81] 2102(0.4%) 2102(0.4%) for-loop ...cts/racket/private/set-types.rkt:152:2 --------------------------------------------------------------------------- ??? [73] 100.0% [82] 310(0.1%) 56(0.0%) ??? ...t-7.0/collects/racket/private/kw.rkt:592:14 ??? [85] 65.8% ??? [88] 16.1% --------------------------------------------------------------------------- for-loop [78] 100.0% [83] 12868(2.4%) 12868(2.4%) for-loop ...cts/racket/private/set-types.rkt:178:5 --------------------------------------------------------------------------- hash-update! [80] 100.0% [84] 2646(0.5%) 50(0.0%) ??? .../optional-posits/src/core/egraph.rkt:219:24 for-loop [91] 98.1% --------------------------------------------------------------------------- ??? [73] 34.2% ??? [82] 65.8% [85] 310(0.1%) 56(0.0%) ??? ...tract/private/arrow-higher-order.rkt:357:33 ival-mult [92] 49.7% ->flonum [94] 16.1% loop [95] 16.1% --------------------------------------------------------------------------- ??? [79] 16.3% for-loop [15] 16.3% loop [19] 17.6% ??? [73] 49.7% [86] 306(0.1%) 100(0.0%) ??? ...ects/racket/contract/private/guts.rkt:746:8 for-loop [89] 50.3% value? [90] 17.0% --------------------------------------------------------------------------- ??? [79] 100.0% [87] 52(0.0%) 0(0.0%) for-loop ...nal-posits/src/core/alt-table.rkt:56:2 atab-add-altn [93] 100.0% --------------------------------------------------------------------------- ??? [82] 100.0% [88] 50(0.0%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:361:33 ival-add [96] 100.0% --------------------------------------------------------------------------- ??? [86] 0.1% variable? [51] 11.7% constant? [77] 88.2% [89] 121570(23.1%) 93548(17.8%) for-loop ...onal-posits/src/syntax/types.rkt:18:19 ??? [97] 15.1% keyword-apply [98] 8.0% --------------------------------------------------------------------------- ??? [86] 0.1% variable? [51] 11.9% constant? [77] 88.0% [90] 72710(13.8%) 56608(10.8%) value? ...ptional-posits/src/syntax/types.rkt:18:0 ??? [97] 15.0% keyword-apply [98] 7.1% --------------------------------------------------------------------------- ??? [84] 100.0% [91] 2596(0.5%) 2596(0.5%) for-loop ...onal-posits/src/core/egraph.rkt:220:26 --------------------------------------------------------------------------- ??? [85] 100.0% [92] 154(0.0%) 0(0.0%) ival-mult ...onal-posits/src/biginterval.rkt:112:0 bfmul [99] 100.0% --------------------------------------------------------------------------- for-loop [87] 100.0% [93] 52(0.0%) 0(0.0%) atab-add-altn ...sits/src/core/alt-table.rkt:202:0 errors [2] 100.0% --------------------------------------------------------------------------- ??? [85] 100.0% [94] 50(0.0%) 50(0.0%) ->flonum ...ie/optional-posits/src/float.rkt:142:0 --------------------------------------------------------------------------- ??? [85] 100.0% [95] 50(0.0%) 50(0.0%) loop .../optional-posits/src/biginterval.rkt:447:9 --------------------------------------------------------------------------- ??? [88] 100.0% [96] 50(0.0%) 0(0.0%) ival-add ...tional-posits/src/biginterval.rkt:94:0 bfmul [99] 100.0% --------------------------------------------------------------------------- for-loop [27] 0.2% value? [90] 37.3% for-loop [89] 62.5% [97] 29282(5.6%) 29282(5.6%) ??? ...-7.0/collects/racket/lazy-require.rkt:106:6 --------------------------------------------------------------------------- value? [90] 34.8% for-loop [89] 65.2% [98] 14892(2.8%) 14892(2.8%) keyword-apply ...lects/racket/private/kw.rkt:421:2 --------------------------------------------------------------------------- ival-add [96] 24.5% ival-mult [92] 75.5% [99] 204(0.0%) 0(0.0%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:821:4 ??? [100] 75.5% new-mpfr [101] 24.5% --------------------------------------------------------------------------- bfmul [99] 100.0% [100] 154(0.0%) 154(0.0%) ??? /opt/racket-7.0/collects/ffi/unsafe.rkt:898:4 --------------------------------------------------------------------------- bfmul [99] 100.0% [101] 50(0.0%) 50(0.0%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ---------------------------------------------------------------------------