Profiling results ----------------- Total cpu time observed: 92020ms (out of 92408ms) Number of samples taken: 266 (once every 346ms) (Hiding functions with self<1.0% and local<2.0%: 1 of 97 hidden) ======================================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ======================================================================================== ??? [30] 100.0% [1] 92020(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [7] 100.0% ---------------------------------------------------------------------------------------- ??? [30] 100.0% [2] 7286(7.9%) 0(0.0%) run-improve41 ...lt-testing/src/mainloop.rkt:225:0 for-loop [8] 48.8% setup-prog!32 [9] 46.6% ??? [25] 4.6% ---------------------------------------------------------------------------------------- loop [94] 100.0% [3] 332(0.4%) 0(0.0%) expand-id-application-form17 (unknown source) syntax-e/no-taint [10] 100.0% ---------------------------------------------------------------------------------------- ??? [58] 100.0% [4] 298(0.3%) 0(0.0%) approximate9 ...t-testing/src/core/taylor.rkt:12:0 loop [11] 100.0% ---------------------------------------------------------------------------------------- hash-ref! [28] 100.0% [5] 298(0.3%) 0(0.0%) ??? ...rbie/alt-testing/src/core/taylor.rkt:295:23 for-loop [13] 100.0% ---------------------------------------------------------------------------------------- hash-ref! [28] 100.0% [6] 298(0.3%) 0(0.0%) ??? ...rbie/alt-testing/src/core/taylor.rkt:392:23 for-loop [12] 100.0% ---------------------------------------------------------------------------------------- profile-thunk16 [1] 100.0% [7] 92020(100.0%) 0(0.0%) run ...et-7.5/share/pkgs/profile-lib/main.rkt:39:2 ??? [30] 100.0% ---------------------------------------------------------------------------------------- run-improve41 [2] 100.0% [8] 3556(3.9%) 0(0.0%) for-loop ...ie/alt-testing/src/mainloop.rkt:240:10 run-iter! [16] 100.0% ---------------------------------------------------------------------------------------- run-improve41 [2] 100.0% [9] 3396(3.7%) 0(0.0%) setup-prog!32 ...alt-testing/src/mainloop.rkt:72:0 loop [14] 71.0% prepare-points [15] 29.0% ---------------------------------------------------------------------------------------- expand-id-application-form17 [3] 100.0% [10] 332(0.4%) 0(0.0%) syntax-e/no-taint (unknown source) loop [94] 100.0% ---------------------------------------------------------------------------------------- approximate9 [4] 100.0% [11] 298(0.3%) 0(0.0%) loop ...erbie/alt-testing/src/core/taylor.rkt:86:4 ??? [58] 100.0% ---------------------------------------------------------------------------------------- ??? [6] 100.0% [12] 298(0.3%) 0(0.0%) for-loop ...alt-testing/src/core/taylor.rkt:396:33 hash-ref! [28] 100.0% ---------------------------------------------------------------------------------------- ??? [5] 100.0% [13] 298(0.3%) 0(0.0%) for-loop ...alt-testing/src/core/taylor.rkt:298:27 hash-ref! [28] 100.0% ---------------------------------------------------------------------------------------- setup-prog!32 [9] 3.5% ??? [30] 96.5% [14] 68374(74.3%) 0(0.0%) loop ...es/herbie/alt-testing/src/points.rkt:238:2 loop [17] 39.1% loop [23] 31.7% make-exacts* [24] 28.7% for-loop [20] 0.5% ---------------------------------------------------------------------------------------- setup-prog!32 [9] 5.0% ??? [30] 95.0% [15] 19756(21.5%) 0(0.0%) prepare-points ...alt-testing/src/points.rkt:214:0 loop [23] 34.2% loop [17] 32.2% make-exacts* [24] 32.1% for-loop [20] 1.5% ---------------------------------------------------------------------------------------- for-loop [8] 100.0% [16] 3556(3.9%) 0(0.0%) run-iter! ...ie/alt-testing/src/mainloop.rkt:206:0 simplify! [18] 46.6% finalize-iter! [19] 36.6% localize-error [21] 8.4% gen-series! [22] 8.4% ---------------------------------------------------------------------------------------- prepare-points [15] 19.2% loop [14] 80.8% [17] 33056(35.9%) 0(0.0%) loop ...es/herbie/alt-testing/src/points.rkt:158:2 loop [23] 51.9% make-exacts* [24] 48.1% ---------------------------------------------------------------------------------------- run-iter! [16] 100.0% [18] 1656(1.8%) 0(0.0%) simplify! ...ie/alt-testing/src/mainloop.rkt:146:0 for-loop [26] 100.0% ---------------------------------------------------------------------------------------- run-iter! [16] 100.0% [19] 1302(1.4%) 0(0.0%) finalize-iter! ...t-testing/src/mainloop.rkt:160:0 ??? [25] 100.0% ---------------------------------------------------------------------------------------- prepare-points [15] 45.3% loop [14] 54.7% [20] 662(0.7%) 0(0.0%) for-loop ...rbie/alt-testing/src/points.rkt:255:26 ??? [27] 100.0% ---------------------------------------------------------------------------------------- run-iter! [16] 100.0% [21] 300(0.3%) 0(0.0%) localize-error ...sting/src/core/localize.rkt:47:0 hash-ref! [28] 100.0% ---------------------------------------------------------------------------------------- run-iter! [16] 100.0% [22] 298(0.3%) 0(0.0%) gen-series! .../alt-testing/src/mainloop.rkt:120:0 for-loop [29] 100.0% ---------------------------------------------------------------------------------------- prepare-points [15] 14.8% loop [17] 37.7% loop [14] 47.5% [23] 45594(49.5%) 0(0.0%) loop ...es/herbie/alt-testing/src/points.rkt:141:4 loop [31] 98.6% ??? [52] 1.4% ---------------------------------------------------------------------------------------- prepare-points [15] 15.1% loop [17] 37.9% loop [14] 46.9% [24] 41874(45.5%) 0(0.0%) make-exacts* ...e/alt-testing/src/points.rkt:138:0 loop [31] 97.6% eval-prog [48] 2.4% ---------------------------------------------------------------------------------------- f165 [54] 13.0% run-improve41 [2] 14.5% ??? [83] 15.8% finalize-iter! [19] 56.6% [25] 2300(2.5%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:486:18 for-loop [32] 71.1% hash-has-key? [33] 28.9% ---------------------------------------------------------------------------------------- simplify! [18] 100.0% [26] 1656(1.8%) 0(0.0%) for-loop ...bie/alt-testing/src/mainloop.rkt:150:6 ??? [30] 100.0% ---------------------------------------------------------------------------------------- for-loop [20] 100.0% [27] 662(0.7%) 0(0.0%) ??? ...ies/herbie/alt-testing/src/points.rkt:230:7 for-loop [34] 54.7% ??? [35] 45.3% ---------------------------------------------------------------------------------------- for-loop [13] 12.5% for-loop [12] 12.5% ??? [36] 12.5% ??? [58] 12.5% localize-error [21] 50.2% [28] 598(0.6%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 parse-loop14 [68] 50.2% ??? [36] 12.5% ??? [6] 12.5% ??? [5] 12.5% simplify [37] 12.5% ---------------------------------------------------------------------------------------- gen-series! [22] 100.0% [29] 298(0.3%) 0(0.0%) for-loop ...bie/alt-testing/src/mainloop.rkt:126:7 taylor-alt [38] 100.0% ---------------------------------------------------------------------------------------- for-loop [26] 0.6% run [7] 49.7% [30] 92020(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 49.7% loop [14] 35.8% prepare-points [15] 10.2% run-improve41 [2] 3.7% unpack24 [39] 0.6% ---------------------------------------------------------------------------------------- loop [23] 1.1% make-exacts* [24] 2.0% loop [31] 96.9% [31] 85826(93.3%) 360(0.4%) loop ...-7.5/collects/racket/private/map.rkt:40:19 loop [31] 96.9% ??? [52] 3.0% ??? [58] 0.1% ---------------------------------------------------------------------------------------- ??? [25] 100.0% [32] 1636(1.8%) 0(0.0%) for-loop ...lt-testing/src/core/alt-table.rkt:53:2 atab-add-altn [40] 100.0% ---------------------------------------------------------------------------------------- ??? [25] 100.0% [33] 664(0.7%) 0(0.0%) hash-has-key? ...ket/private/more-scheme.rkt:371:2 ??? [41] 100.0% ---------------------------------------------------------------------------------------- ??? [27] 100.0% [34] 362(0.4%) 0(0.0%) for-loop ...erbie/alt-testing/src/points.rkt:231:9 ??? [58] 100.0% ---------------------------------------------------------------------------------------- ??? [27] 100.0% [35] 300(0.3%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:379:33 ??? [74] 100.0% ---------------------------------------------------------------------------------------- hash-ref! [28] 100.0% [36] 298(0.3%) 0(0.0%) ??? ...erbie/alt-testing/src/core/taylor.rkt:44:15 hash-ref! [28] 100.0% ---------------------------------------------------------------------------------------- hash-ref! [28] 100.0% [37] 298(0.3%) 0(0.0%) simplify ...e/alt-testing/src/core/reduce.rkt:16:0 f165 [54] 100.0% ---------------------------------------------------------------------------------------- for-loop [29] 100.0% [38] 298(0.3%) 0(0.0%) taylor-alt ...erbie/alt-testing/src/glue.rkt:134:0 for-loop [42] 100.0% ---------------------------------------------------------------------------------------- ??? [30] 100.0% [39] 1656(1.8%) 0(0.0%) unpack24 ...alt-testing/src/core/simplify.rkt:43:0 ??? [46] 61.5% for-loop [44] 38.5% ---------------------------------------------------------------------------------------- for-loop [32] 100.0% [40] 1636(1.8%) 0(0.0%) atab-add-altn ...ting/src/core/alt-table.rkt:239:0 best-and-tied-at-points [43] 100.0% ---------------------------------------------------------------------------------------- hash-has-key? [33] 100.0% [41] 664(0.7%) 300(0.3%) ??? ...ects/racket/contract/private/list.rkt:808:4 for-loop [45] 54.8% ---------------------------------------------------------------------------------------- taylor-alt [38] 100.0% [42] 298(0.3%) 0(0.0%) for-loop .../herbie/alt-testing/src/glue.rkt:139:6 ??? [58] 100.0% ---------------------------------------------------------------------------------------- atab-add-altn [40] 100.0% [43] 1636(1.8%) 0(0.0%) best-and-tied-at-points ...ore/alt-table.rkt:153:0 errors [47] 100.0% ---------------------------------------------------------------------------------------- unpack24 [39] 100.0% [44] 638(0.7%) 0(0.0%) for-loop ...lt-testing/src/core/simplify.rkt:66:12 ??? [46] 100.0% ---------------------------------------------------------------------------------------- ??? [41] 100.0% [45] 364(0.4%) 364(0.4%) for-loop ...acket/contract/private/list.rkt:814:12 ---------------------------------------------------------------------------------------- for-loop [44] 38.5% unpack24 [39] 61.5% [46] 1656(1.8%) 0(0.0%) ??? ...rbie/alt-testing/src/core/simplify.rkt:77:0 iterate-egraph!13 [49] 80.4% mk-egraph [51] 19.6% ---------------------------------------------------------------------------------------- best-and-tied-at-points [43] 100.0% [47] 1636(1.8%) 0(0.0%) errors .../herbie/alt-testing/src/points.rkt:271:0 for-loop [50] 79.7% eval-prog [48] 20.3% ---------------------------------------------------------------------------------------- errors [47] 24.5% make-exacts* [24] 75.5% [48] 1354(1.5%) 0(0.0%) eval-prog ...ie/alt-testing/src/programs.rkt:150:0 f165 [54] 49.0% ??? [58] 26.4% temp85_0 [55] 24.5% ---------------------------------------------------------------------------------------- ??? [46] 100.0% [49] 1332(1.4%) 0(0.0%) iterate-egraph!13 ...g/src/core/simplify.rkt:105:0 one-iter [53] 100.0% ---------------------------------------------------------------------------------------- errors [47] 100.0% [50] 1304(1.4%) 334(0.4%) for-loop ...erbie/alt-testing/src/points.rkt:274:4 ??? [52] 51.4% ??? [58] 23.0% ---------------------------------------------------------------------------------------- ??? [46] 100.0% [51] 324(0.4%) 0(0.0%) mk-egraph ...alt-testing/src/core/egraph.rkt:131:0 mk-enode-rec! [56] 100.0% ---------------------------------------------------------------------------------------- loop [23] 0.7% for-loop [50] 0.8% loop [31] 98.5% [52] 85076(92.5%) 9328(10.1%) ??? ...s/herbie/alt-testing/src/programs.rkt:156:4 ??? [73] 74.1% bffmod [57] 7.9% bf [59] 3.4% bf* [64] 2.4% parse-loop14 [68] 0.4% ...higher-order.rkt:379:33 [66] 0.4% ---------------------------------------------------------------------------------------- iterate-egraph!13 [49] 100.0% [53] 1332(1.4%) 310(0.3%) one-iter ...lt-testing/src/core/simplify.rkt:116:0 for-loop [60] 76.7% ---------------------------------------------------------------------------------------- simplify [37] 10.3% f165 [54] 18.9% map [63] 20.7% eval-prog [48] 50.1% [54] 962(1.0%) 0(0.0%) f165 .../collects/racket/match/compiler.rkt:507:40 ??? [25] 31.2% map [63] 31.0% f165 [54] 18.9% ??? [58] 18.9% ---------------------------------------------------------------------------------------- eval-prog [48] 100.0% [55] 332(0.4%) 0(0.0%) temp85_0 (unknown source) compile15 [61] 100.0% ---------------------------------------------------------------------------------------- mk-egraph [51] 100.0% [56] 324(0.4%) 0(0.0%) mk-enode-rec! ...testing/src/core/egraph.rkt:122:0 mk-enode! [62] 100.0% ---------------------------------------------------------------------------------------- ??? [52] 100.0% [57] 6698(7.3%) 1084(1.2%) bffmod .../alt-testing/src/syntax/syntax.rkt:332:0 ??? [73] 62.2% ...higher-order.rkt:379:33 [66] 16.2% bf* [64] 5.4% ---------------------------------------------------------------------------------------- loop [11] 3.0% location-do [72] 3.0% for-loop [42] 3.0% for-loop [50] 8.9% eval-prog [48] 10.6% for-loop [34] 10.8% f165 [54] 10.8% loop [31] 50.0% [58] 3362(3.7%) 662(0.7%) ??? ...tract/private/arrow-higher-order.rkt:375:33 ->flonum [65] 50.0% ??? [83] 10.8% ->bf [69] 10.6% location-do [72] 3.0% approximate9 [4] 3.0% hash-ref! [28] 3.0% ---------------------------------------------------------------------------------------- ??? [52] 100.0% [59] 2862(3.1%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:615:2 new-mpfr [87] 74.5% ??? [88] 25.5% ---------------------------------------------------------------------------------------- for-loop [60] 50.0% one-iter [53] 50.0% [60] 1022(1.1%) 0(0.0%) for-loop ...t-testing/src/core/simplify.rkt:126:12 for-loop [60] 50.0% match-e [67] 50.0% ---------------------------------------------------------------------------------------- temp85_0 [55] 100.0% [61] 332(0.4%) 0(0.0%) compile15 (unknown source) temp91_0 [70] 100.0% ---------------------------------------------------------------------------------------- mk-enode-rec! [56] 100.0% [62] 324(0.4%) 0(0.0%) mk-enode! ...alt-testing/src/core/egraph.rkt:102:0 new-enode [71] 100.0% ---------------------------------------------------------------------------------------- parse-loop14 [68] 25.0% f165 [54] 75.0% [63] 298(0.3%) 0(0.0%) map ...t-7.5/collects/racket/private/map.rkt:35:13 f165 [54] 50.0% parse-loop14 [68] 50.0% ---------------------------------------------------------------------------------------- bffmod [57] 14.8% ??? [52] 85.2% [64] 2438(2.6%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [73] 100.0% ---------------------------------------------------------------------------------------- ??? [58] 100.0% [65] 1680(1.8%) 982(1.1%) ->flonum ...rbie/alt-testing/src/programs.rkt:37:0 return/no-unsupplied [75] 41.5% ---------------------------------------------------------------------------------------- ??? [52] 23.1% bffmod [57] 76.9% [66] 1412(1.5%) 0(0.0%) ...higher-order.rkt:379:33 (unknown source) ??? [74] 100.0% ---------------------------------------------------------------------------------------- for-loop [60] 100.0% [67] 1022(1.1%) 662(0.7%) match-e ...ie/alt-testing/src/core/ematch.rkt:46:0 for-loop [76] 35.2% ---------------------------------------------------------------------------------------- parse-loop14 [68] 10.6% map [63] 21.3% hash-ref! [28] 32.1% ??? [52] 36.0% [68] 934(1.0%) 336(0.4%) parse-loop14 ...s/racket/match/compiler.rkt:418:15 ??? [73] 32.1% map [63] 10.6% parse-loop14 [68] 10.6% loop [94] 10.6% ---------------------------------------------------------------------------------------- ??? [58] 100.0% [69] 358(0.4%) 0(0.0%) ->bf ...s/herbie/alt-testing/src/programs.rkt:53:0 successfully-got-the-right-kind-of-function [77]100.0% ---------------------------------------------------------------------------------------- compile15 [61] 100.0% [70] 332(0.4%) 0(0.0%) temp91_0 (unknown source) temp118_0 [78] 100.0% ---------------------------------------------------------------------------------------- mk-enode! [62] 100.0% [71] 324(0.4%) 0(0.0%) new-enode ...e/alt-testing/src/core/enode.rkt:97:0 type-of-enode-expr [79] 100.0% ---------------------------------------------------------------------------------------- ??? [58] 100.0% [72] 298(0.3%) 0(0.0%) location-do .../alt-testing/src/programs.rkt:116:0 ??? [58] 100.0% ---------------------------------------------------------------------------------------- parse-loop14 [68] 0.4% bf* [64] 3.5% bffmod [57] 6.0% ??? [52] 90.1% [73] 69982(76.1%) 60906(66.2%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfexpt [80] 8.5% bfatan [81] 2.5% bffloor [82] 2.0% ---------------------------------------------------------------------------------------- ??? [35] 17.5% ...higher-order.rkt:379:33 [66] 82.5% [74] 1712(1.9%) 1352(1.5%) ??? ...cts/racket/contract/private/list.rkt:209:10 ??? [83] 21.0% ---------------------------------------------------------------------------------------- ->flonum [65] 100.0% [75] 698(0.8%) 362(0.4%) return/no-unsupplied ...ct/private/arr-i.rkt:566:0 filter [85] 48.1% ---------------------------------------------------------------------------------------- match-e [67] 100.0% [76] 360(0.4%) 360(0.4%) for-loop .../alt-testing/src/core/ematch.rkt:59:11 ---------------------------------------------------------------------------------------- ->bf [69] 100.0% [77] 358(0.4%) 358(0.4%) successfully-got-the-right-kind-of-function ...5:4 ---------------------------------------------------------------------------------------- temp91_0 [70] 100.0% [78] 332(0.4%) 0(0.0%) temp118_0 (unknown source) expand-capturing-lifts [84] 100.0% ---------------------------------------------------------------------------------------- new-enode [71] 100.0% [79] 324(0.4%) 0(0.0%) type-of-enode-expr ...ting/src/core/enode.rkt:68:0 get-sigs [86] 100.0% ---------------------------------------------------------------------------------------- ??? [73] 100.0% [80] 5950(6.5%) 1018(1.1%) bfexpt ...lib/math/private/bigfloat/mpfr.rkt:835:4 ??? [88] 42.4% new-mpfr [87] 40.5% ---------------------------------------------------------------------------------------- ??? [73] 100.0% [81] 1750(1.9%) 0(0.0%) bfatan ...lib/math/private/bigfloat/mpfr.rkt:632:4 new-mpfr [87] 81.1% ??? [88] 18.9% ---------------------------------------------------------------------------------------- ??? [73] 100.0% [82] 1376(1.5%) 0(0.0%) bffloor ...ib/math/private/bigfloat/mpfr.rkt:730:4 new-mpfr [87] 52.2% ??? [88] 47.8% ---------------------------------------------------------------------------------------- ??? [74] 49.7% ??? [58] 50.3% [83] 724(0.8%) 360(0.4%) ??? ...ects/racket/contract/private/guts.rkt:770:8 ??? [25] 50.3% ---------------------------------------------------------------------------------------- loop [94] 47.3% temp118_0 [78] 52.7% [84] 630(0.7%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [89] 52.7% loop [94] 47.3% ---------------------------------------------------------------------------------------- return/no-unsupplied [75] 100.0% [85] 336(0.4%) 336(0.4%) filter ...5/collects/racket/private/list.rkt:256:2 ---------------------------------------------------------------------------------------- type-of-enode-expr [79] 100.0% [86] 324(0.4%) 0(0.0%) get-sigs ...bie/alt-testing/src/type-check.rkt:5:0 ...sting/src/common.rkt:47:2 [90] 100.0% ---------------------------------------------------------------------------------------- bffloor [82] 10.8% bfatan [81] 21.3% bf [59] 31.9% bfexpt [80] 36.1% [87] 6678(7.3%) 6678(7.3%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:348:0 ---------------------------------------------------------------------------------------- bfatan [81] 7.8% bffloor [82] 15.5% bf [59] 17.2% bfexpt [80] 59.5% [88] 4242(4.6%) 4242(4.6%) ??? /opt/racket-7.5/collects/ffi/unsafe.rkt:938:4 ---------------------------------------------------------------------------------------- expand-capturing-lifts [84] 100.0% [89] 332(0.4%) 0(0.0%) lambda-clause-expander (unknown source) finish-bodys [91] 100.0% ---------------------------------------------------------------------------------------- get-sigs [86] 100.0% [90] 324(0.4%) 0(0.0%) ...sting/src/common.rkt:47:2 ...e/arr-i.rkt:976:19 ??? [92] 100.0% ---------------------------------------------------------------------------------------- lambda-clause-expander [89] 25.0% for-loop [93] 75.0% [91] 332(0.4%) 0(0.0%) finish-bodys (unknown source) for-loop [93] 100.0% ---------------------------------------------------------------------------------------- ...sting/src/common.rkt:47:2 [90] 100.0% [92] 324(0.4%) 324(0.4%) ??? ...s/racket/contract/private/arr-i.rkt:1321:39 ---------------------------------------------------------------------------------------- finish-bodys [91] 100.0% [93] 332(0.4%) 0(0.0%) for-loop (unknown source) finish-bodys [91] 75.0% loop [94] 25.0% ---------------------------------------------------------------------------------------- expand-capturing-lifts [84] 23.7% parse-loop14 [68] 23.7% syntax-e/no-taint [10] 26.3% for-loop [93] 26.3% [94] 630(0.7%) 298(0.3%) loop (unknown source) expand-id-application-form17 [3] 26.3% gf [95] 26.3% expand-capturing-lifts [84] 23.7% ---------------------------------------------------------------------------------------- loop [94] 100.0% [95] 332(0.4%) 0(0.0%) gf (unknown source) propagation-apply [96] 100.0% ---------------------------------------------------------------------------------------- gf [95] 100.0% [96] 332(0.4%) 332(0.4%) propagation-apply (unknown source) ----------------------------------------------------------------------------------------