Profiling results ----------------- Total cpu time observed: 135376ms (out of 137984ms) Number of samples taken: 343 (once every 395ms) (Hiding functions with self<1.0% and local<2.0%: 2 of 89 hidden) =============================================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee =============================================================================================== ??? [9] 100.0% [1] 135376(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [7] 100.0% ----------------------------------------------------------------------------------------------- hash-ref! [40] 100.0% [2] 1050(0.8%) 0(0.0%) parse-loop15 ...s/racket/match/compiler.rkt:418:15 loop [32] 100.0% ----------------------------------------------------------------------------------------------- hash-ref! [40] 100.0% [3] 1050(0.8%) 0(0.0%) f9 ....0/collects/racket/match/compiler.rkt:507:40 map [8] 100.0% ----------------------------------------------------------------------------------------------- hash-ref! [40] 100.0% [4] 956(0.7%) 0(0.0%) ??? ...tlies/herbie/develop/src/programs.rkt:189:5 loop [32] 100.0% ----------------------------------------------------------------------------------------------- loop [10] 100.0% [5] 390(0.3%) 0(0.0%) ??? .../herbie/develop/src/core/localize.rkt:43:32 ??? [33] 100.0% ----------------------------------------------------------------------------------------------- for-loop [82] 100.0% [6] 330(0.2%) 0(0.0%) expand-identifier (unknown source) resolve+shift28 [78] 100.0% ----------------------------------------------------------------------------------------------- profile-thunk16 [1] 100.0% [7] 135376(100.0%) 0(0.0%) run ...et-7.0/share/pkgs/profile-lib/main.rkt:39:2 ??? [9] 100.0% ----------------------------------------------------------------------------------------------- f9 [3] 100.0% [8] 1050(0.8%) 0(0.0%) map ...t-7.0/collects/racket/private/map.rkt:35:13 hash-ref! [40] 50.0% loop [32] 31.4% loop [10] 18.6% ----------------------------------------------------------------------------------------------- for-loop [36] 0.1% run [7] 50.0% [9] 135376(100.0%) 282(0.2%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 50.0% loop [13] 47.5% run-improve40 [11] 2.2% eval-errors [12] 0.1% errors [28] 0.1% ??? [33] 0.1% ----------------------------------------------------------------------------------------------- map [8] 6.2% loop [10] 93.8% [10] 390(0.3%) 0(0.0%) loop ...-7.0/collects/racket/private/map.rkt:54:19 loop [10] 93.8% ??? [5] 6.2% ----------------------------------------------------------------------------------------------- ??? [9] 100.0% [11] 5972(4.4%) 0(0.0%) run-improve40 ...ie/develop/src/mainloop.rkt:336:0 loop [13] 70.9% for-loop [14] 23.6% ??? [15] 5.5% ----------------------------------------------------------------------------------------------- ??? [9] 100.0% [12] 276(0.2%) 0(0.0%) eval-errors ...herbie/develop/src/points.rkt:203:0 for-loop [16] 100.0% ----------------------------------------------------------------------------------------------- run-improve40 [11] 3.2% ??? [9] 96.8% [13] 132774(98.1%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:304:2 loop [24] 52.9% loop [17] 31.3% make-exacts-walkup [25] 12.4% make-exacts-halfpoints [18] 2.5% for-loop [20] 0.8% filter-p&e [22] 0.2% ----------------------------------------------------------------------------------------------- run-improve40 [11] 100.0% [14] 1408(1.0%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:351:4 run-iter! [19] 100.0% ----------------------------------------------------------------------------------------------- run-improve40 [11] 100.0% [15] 328(0.2%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:388:18 make-alt-table [21] 100.0% ----------------------------------------------------------------------------------------------- eval-errors [12] 100.0% [16] 276(0.2%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:205:2 ulp-difference [23] 100.0% ----------------------------------------------------------------------------------------------- loop [13] 100.0% [17] 41558(30.7%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:265:2 loop [24] 56.8% make-exacts-walkup [25] 41.8% eval-prog [34] 1.4% ----------------------------------------------------------------------------------------------- loop [13] 100.0% [18] 3272(2.4%) 0(0.0%) make-exacts-halfpoints ...lop/src/points.rkt:263:0 make-exacts-walkup [25] 58.7% loop [24] 31.8% debug-print [30] 9.5% ----------------------------------------------------------------------------------------------- for-loop [14] 100.0% [19] 1408(1.0%) 0(0.0%) run-iter! ...herbie/develop/src/mainloop.rkt:317:0 localize! [26] 100.0% ----------------------------------------------------------------------------------------------- loop [13] 100.0% [20] 1008(0.7%) 0(0.0%) for-loop ...s/herbie/develop/src/points.rkt:320:47 ??? [27] 100.0% ----------------------------------------------------------------------------------------------- ??? [15] 100.0% [21] 328(0.2%) 0(0.0%) make-alt-table ...elop/src/core/alt-table.rkt:33:0 errors [28] 100.0% ----------------------------------------------------------------------------------------------- loop [13] 100.0% [22] 322(0.2%) 0(0.0%) filter-p&e .../herbie/develop/src/points.rkt:278:0 for-loop [29] 100.0% ----------------------------------------------------------------------------------------------- for-loop [16] 100.0% [23] 276(0.2%) 0(0.0%) ulp-difference ...erbie/develop/src/float.rkt:14:0 ??? [31] 100.0% ----------------------------------------------------------------------------------------------- make-exacts-halfpoints [18] 1.1% loop [17] 24.9% loop [13] 74.0% [24] 94844(70.1%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:248:4 loop [32] 100.0% ----------------------------------------------------------------------------------------------- make-exacts-halfpoints [18] 5.4% loop [13] 46.0% loop [17] 48.6% [25] 35692(26.4%) 0(0.0%) make-exacts-walkup ...develop/src/points.rkt:245:0 loop [32] 93.6% eval-prog [34] 6.4% ----------------------------------------------------------------------------------------------- run-iter! [19] 100.0% [26] 1408(1.0%) 0(0.0%) localize! ...herbie/develop/src/mainloop.rkt:116:0 localize-error [35] 100.0% ----------------------------------------------------------------------------------------------- for-loop [20] 100.0% [27] 1008(0.7%) 0(0.0%) ??? ...ghtlies/herbie/develop/src/points.rkt:129:2 ??? [33] 100.0% ----------------------------------------------------------------------------------------------- ??? [9] 48.4% make-alt-table [21] 51.6% [28] 636(0.5%) 0(0.0%) errors ...lies/herbie/develop/src/points.rkt:228:0 for-loop [36] 100.0% ----------------------------------------------------------------------------------------------- filter-p&e [22] 100.0% [29] 322(0.2%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:280:2 ordinary-value? [37] 100.0% ----------------------------------------------------------------------------------------------- make-exacts-halfpoints [18] 100.0% [30] 312(0.2%) 312(0.2%) debug-print .../herbie/develop/src/debug.rkt:110:0 ----------------------------------------------------------------------------------------------- ulp-difference [23] 100.0% [31] 276(0.2%) 276(0.2%) ??? ...s/racket/contract/private/arr-i.rkt:1252:29 ----------------------------------------------------------------------------------------------- map [8] 0.1% parse-loop15 [2] 0.5% make-exacts-walkup [25] 0.7% ??? [4] 0.7% loop [24] 1.4% loop [32] 96.5% [32] 130240(96.2%) 0(0.0%) loop ...-7.0/collects/racket/private/map.rkt:40:19 loop [32] 96.5% composed [38] 2.1% hash-ref! [40] 1.2% ??? [55] 0.1% ??? [33] 0.0% ??? [44] 0.0% ----------------------------------------------------------------------------------------------- for-loop [48] 4.4% ??? [9] 9.0% ??? [5] 11.4% ??? [27] 25.2% loop [32] 50.0% [33] 3410(2.5%) 674(0.5%) ??? ...tract/private/arrow-higher-order.rkt:357:33 ->flonum [39] 70.4% sample-multi-bounded [42] 14.2% ----------------------------------------------------------------------------------------------- loop [17] 20.6% make-exacts-walkup [25] 79.4% [34] 2898(2.1%) 0(0.0%) eval-prog ...herbie/develop/src/programs.rkt:130:0 temp68_2 [41] 44.7% hash-ref! [40] 33.0% loop [72] 22.4% ----------------------------------------------------------------------------------------------- localize! [26] 100.0% [35] 1408(1.0%) 358(0.3%) localize-error ...velop/src/core/localize.rkt:52:0 hash-ref! [40] 74.6% ----------------------------------------------------------------------------------------------- errors [28] 100.0% [36] 636(0.5%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:230:2 ??? [43] 51.6% ??? [9] 48.4% ----------------------------------------------------------------------------------------------- for-loop [29] 100.0% [37] 322(0.2%) 322(0.2%) ordinary-value? ...rbie/develop/src/float.rkt:54:0 ----------------------------------------------------------------------------------------------- loop [32] 100.0% [38] 124148(91.7%) 0(0.0%) composed ...ollects/racket/private/list.rkt:321:16 ??? [44] 100.0% ----------------------------------------------------------------------------------------------- ->flonum [39] 8.1% ??? [33] 91.9% [39] 2402(1.8%) 1748(1.3%) ->flonum ...s/herbie/develop/src/programs.rkt:38:0 return/no-unsupplied [47] 15.0% ??? [74] 12.2% ->flonum [39] 8.1% ----------------------------------------------------------------------------------------------- map [8] 13.1% localize-error [35] 13.1% eval-prog [34] 15.8% loop [32] 58.0% [40] 2006(1.5%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 ??? [4] 31.9% parse-loop15 [2] 26.2% f9 [3] 26.2% bigfloat-hash [46] 15.8% ----------------------------------------------------------------------------------------------- eval-prog [34] 100.0% [41] 1294(1.0%) 0(0.0%) temp68_2 (unknown source) compile16 [45] 100.0% ----------------------------------------------------------------------------------------------- ??? [33] 100.0% [42] 634(0.5%) 0(0.0%) sample-multi-bounded ...evelop/src/points.rkt:16:0 return/no-unsupplied [47] 52.7% for-loop [48] 47.3% ----------------------------------------------------------------------------------------------- for-loop [36] 100.0% [43] 328(0.2%) 328(0.2%) ??? ...7.0/collects/racket/private/for.rkt:1208:14 ----------------------------------------------------------------------------------------------- loop [32] 1.6% composed [38] 98.4% [44] 126196(93.2%) 2060(1.5%) ??? ...tlies/herbie/develop/src/programs.rkt:146:2 ??? [49] 95.4% bf [50] 2.7% ----------------------------------------------------------------------------------------------- temp68_2 [41] 100.0% [45] 1294(1.0%) 0(0.0%) compile16 (unknown source) temp74_0 [51] 100.0% ----------------------------------------------------------------------------------------------- hash-ref! [40] 100.0% [46] 956(0.7%) 0(0.0%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:167:0 bfcanonicalize [52] 64.0% sig+exp->bigfloat [53] 36.0% ----------------------------------------------------------------------------------------------- sample-multi-bounded [42] 48.1% ->flonum [39] 51.9% [47] 694(0.5%) 360(0.3%) return/no-unsupplied ...ct/private/arr-i.rkt:534:0 filter [54] 48.1% ----------------------------------------------------------------------------------------------- sample-multi-bounded [42] 100.0% [48] 300(0.2%) 0(0.0%) for-loop ...ies/herbie/develop/src/points.rkt:26:4 ??? [33] 100.0% ----------------------------------------------------------------------------------------------- ??? [44] 100.0% [49] 120334(88.9%) 352(0.3%) ??? ...t-7.0/collects/racket/private/kw.rkt:592:14 ??? [55] 99.7% ----------------------------------------------------------------------------------------------- ??? [44] 100.0% [50] 3448(2.5%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [73] 88.1% ??? [74] 11.9% ----------------------------------------------------------------------------------------------- compile16 [45] 100.0% [51] 1294(1.0%) 0(0.0%) temp74_0 (unknown source) expand-single [56] 100.0% ----------------------------------------------------------------------------------------------- bigfloat-hash [46] 100.0% [52] 612(0.5%) 612(0.5%) bfcanonicalize .../private/bigfloat/mpfr.rkt:157:0 ----------------------------------------------------------------------------------------------- bigfloat-hash [46] 100.0% [53] 344(0.3%) 0(0.0%) sig+exp->bigfloat ...ivate/bigfloat/mpfr.rkt:387:0 new-mpfr [73] 100.0% ----------------------------------------------------------------------------------------------- return/no-unsupplied [47] 100.0% [54] 334(0.2%) 334(0.2%) filter ...0/collects/racket/private/list.rkt:256:2 ----------------------------------------------------------------------------------------------- loop [32] 0.5% ??? [49] 99.5% [55] 120642(89.1%) 338(0.2%) ??? ...tract/private/arrow-higher-order.rkt:361:33 bf-complex-exp [57] 72.9% bf-complex-div [58] 20.4% bf-complex-neg [59] 4.3% bf-complex-add [60] 2.1% ----------------------------------------------------------------------------------------------- temp74_0 [51] 100.0% [56] 1294(1.0%) 0(0.0%) expand-single (unknown source) expand-capturing-lifts [61] 100.0% ----------------------------------------------------------------------------------------------- ??? [55] 100.0% [57] 87930(65.0%) 286(0.2%) bf-complex-exp .../develop/src/bigcomplex.rkt:37:0 ??? [66] 92.9% bf* [62] 6.0% ...higher-order.rkt:361:33 [63] 0.8% ----------------------------------------------------------------------------------------------- ??? [55] 100.0% [58] 24614(18.2%) 678(0.5%) bf-complex-div .../develop/src/bigcomplex.rkt:54:0 ??? [66] 51.0% bf* [62] 43.4% ...higher-order.rkt:361:33 [63] 2.8% ----------------------------------------------------------------------------------------------- ??? [55] 100.0% [59] 5172(3.8%) 282(0.2%) bf-complex-neg .../develop/src/bigcomplex.rkt:20:0 ??? [66] 94.5% ----------------------------------------------------------------------------------------------- ??? [55] 100.0% [60] 2588(1.9%) 868(0.6%) bf-complex-add .../develop/src/bigcomplex.rkt:23:0 ??? [66] 53.2% ...higher-order.rkt:361:33 [63] 13.3% ----------------------------------------------------------------------------------------------- loop [72] 33.4% expand-single [56] 66.6% [61] 1942(1.4%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [64] 66.6% loop [72] 18.3% dispatch-transformer41 [65] 15.0% ----------------------------------------------------------------------------------------------- bf-complex-exp [57] 32.9% bf-complex-div [58] 67.1% [62] 15920(11.8%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [66] 100.0% ----------------------------------------------------------------------------------------------- bf-complex-add [60] 19.7% bf-complex-div [58] 40.0% bf-complex-exp [57] 40.3% [63] 1742(1.3%) 358(0.3%) ...higher-order.rkt:361:33 (unknown source) ??? [67] 79.4% ----------------------------------------------------------------------------------------------- expand-capturing-lifts [61] 100.0% [64] 1294(1.0%) 0(0.0%) lambda-clause-expander (unknown source) finish-bodys [68] 100.0% ----------------------------------------------------------------------------------------------- expand-capturing-lifts [61] 100.0% [65] 292(0.2%) 0(0.0%) dispatch-transformer41 (unknown source) apply-transformer52 [69] 100.0% ----------------------------------------------------------------------------------------------- bf-complex-add [60] 1.2% bf-complex-neg [59] 4.2% bf-complex-div [58] 10.8% bf* [62] 13.7% bf-complex-exp [57] 70.2% [66] 116448(86.0%) 74664(55.2%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfadd [70] 18.2% bfcos [71] 17.7% ----------------------------------------------------------------------------------------------- ...higher-order.rkt:361:33 [63] 100.0% [67] 1384(1.0%) 1384(1.0%) ??? ...cts/racket/contract/private/list.rkt:209:10 ----------------------------------------------------------------------------------------------- lambda-clause-expander [64] 17.8% for-loop [82] 82.2% [68] 1294(1.0%) 0(0.0%) finish-bodys (unknown source) for-loop [82] 100.0% ----------------------------------------------------------------------------------------------- dispatch-transformer41 [65] 100.0% [69] 292(0.2%) 0(0.0%) apply-transformer52 (unknown source) loop [72] 100.0% ----------------------------------------------------------------------------------------------- ??? [66] 100.0% [70] 21136(15.6%) 2112(1.6%) bfadd ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [73] 63.3% ??? [74] 26.7% ----------------------------------------------------------------------------------------------- ??? [66] 100.0% [71] 20648(15.3%) 2164(1.6%) bfcos ...-lib/math/private/bigfloat/mpfr.rkt:618:4 new-mpfr [73] 71.1% ??? [74] 18.4% ----------------------------------------------------------------------------------------------- loop [72] 7.4% expand-capturing-lifts [61] 7.4% apply-transformer52 [69] 9.1% syntax-e/no-taint [80] 10.2% eval-prog [34] 16.4% for-loop [82] 49.6% [72] 1612(1.2%) 296(0.2%) loop (unknown source) expand-id-application-form17 [75] 31.2% expand-capturing-lifts [61] 16.4% gf [76] 10.2% core-form-sym [77] 9.1% loop [72] 7.4% for-loop [82] 7.4% ----------------------------------------------------------------------------------------------- sig+exp->bigfloat [53] 1.1% bf [50] 9.7% bfadd [70] 42.5% bfcos [71] 46.7% [73] 31436(23.2%) 31436(23.2%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ----------------------------------------------------------------------------------------------- ->flonum [39] 2.9% bf [50] 4.0% bfcos [71] 37.4% bfadd [70] 55.6% [74] 10158(7.5%) 10158(7.5%) ??? /opt/racket-7.0/collects/ffi/unsafe.rkt:898:4 ----------------------------------------------------------------------------------------------- loop [72] 100.0% [75] 668(0.5%) 0(0.0%) expand-id-application-form17 (unknown source) resolve+shift28 [78] 50.6% syntax-e/no-taint [80] 49.4% ----------------------------------------------------------------------------------------------- loop [72] 100.0% [76] 330(0.2%) 0(0.0%) gf (unknown source) propagation-apply [79] 100.0% ----------------------------------------------------------------------------------------------- loop [72] 100.0% [77] 292(0.2%) 0(0.0%) core-form-sym (unknown source) resolve+shift28 [78] 100.0% ----------------------------------------------------------------------------------------------- core-form-sym [77] 30.4% expand-identifier [6] 34.4% expand-id-application-form17 [75] 35.2% [78] 960(0.7%) 0(0.0%) resolve+shift28 (unknown source) fallback-loop [81] 100.0% ----------------------------------------------------------------------------------------------- gf [76] 100.0% [79] 330(0.2%) 330(0.2%) propagation-apply (unknown source) ----------------------------------------------------------------------------------------------- expand-id-application-form17 [75] 100.0% [80] 330(0.2%) 0(0.0%) syntax-e/no-taint (unknown source) loop [72] 100.0% ----------------------------------------------------------------------------------------------- resolve+shift28 [78] 100.0% [81] 960(0.7%) 0(0.0%) fallback-loop (unknown source) for-loop [82] 100.0% ----------------------------------------------------------------------------------------------- temp37_0 [84] 6.1% run-module-instance!125 [85] 6.1% loop [72] 6.1% for-loop [82] 11.3% fallback-loop [81] 13.8% finish-bodys [68] 56.6% [82] 1942(1.4%) 960(0.7%) for-loop (unknown source) finish-bodys [68] 47.1% for-loop [82] 11.3% loop [72] 7.6% run-module-instance!125 [85] 6.1% temp37_0 [84] 6.1% namespace-module-use->module+linklet-instances144 [83] 6.1% expand-identifier [6] 1.9% ----------------------------------------------------------------------------------------------- for-loop [82] 100.0% [83] 356(0.3%) 0(0.0%) namespace-module-use->module+linklet-instances144 ... module-path-index-resolve [86] 100.0% ----------------------------------------------------------------------------------------------- for-loop [82] 100.0% [84] 356(0.3%) 0(0.0%) temp37_0 (unknown source) for-loop [82] 100.0% ----------------------------------------------------------------------------------------------- for-loop [82] 100.0% [85] 356(0.3%) 0(0.0%) run-module-instance!125 (unknown source) for-loop [82] 100.0% ----------------------------------------------------------------------------------------------- namespace-module-use->module+linklet-instances144 [83]100.0% [86] 356(0.3%) 0(0.0%) module-path-index-resolve (unknown source) standard-module-name-resolver [87] 100.0% ----------------------------------------------------------------------------------------------- module-path-index-resolve [86] 100.0% [87] 356(0.3%) 356(0.3%) standard-module-name-resolver (unknown source) -----------------------------------------------------------------------------------------------