Profiling results ----------------- Total cpu time observed: 100856ms (out of 103976ms) Number of samples taken: 218 (once every 463ms) =========================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee =========================================================================== ??? [6] 100.0% [1] 100856(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [4] 100.0% --------------------------------------------------------------------------- hash-ref! [61] 100.0% [2] 3736(3.7%) 0(0.0%) ??? ...htlies/herbie/master/src/programs.rkt:151:5 loop [54] 100.0% --------------------------------------------------------------------------- ??? [6] 100.0% [3] 378(0.4%) 0(0.0%) errors ...tlies/herbie/master/src/points.rkt:205:0 for-loop [5] 100.0% --------------------------------------------------------------------------- profile-thunk16 [1] 100.0% [4] 100856(100.0%) 0(0.0%) run ...et-7.0/share/pkgs/profile-lib/main.rkt:39:2 ??? [6] 100.0% --------------------------------------------------------------------------- errors [3] 100.0% [5] 378(0.4%) 0(0.0%) for-loop ...ies/herbie/master/src/points.rkt:207:2 ??? [6] 100.0% --------------------------------------------------------------------------- for-loop [5] 0.1% run [4] 49.9% [6] 100856(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 49.9% loop [11] 44.3% run-improve47 [7] 5.2% errors-score [9] 0.2% eval-errors [10] 0.1% errors [3] 0.1% infer-double-representation [8] 0.1% --------------------------------------------------------------------------- ??? [6] 100.0% [7] 10414(10.3%) 0(0.0%) run-improve47 ...bie/master/src/mainloop.rkt:342:0 simplify! [12] 57.2% loop [11] 33.4% for-loop [13] 9.4% --------------------------------------------------------------------------- ??? [6] 100.0% [8] 378(0.4%) 0(0.0%) infer-double-representation .../src/float.rkt:22:0 for-loop [14] 100.0% --------------------------------------------------------------------------- ??? [6] 100.0% [9] 312(0.3%) 0(0.0%) errors-score ...herbie/master/src/points.rkt:196:0 loop [15] 100.0% --------------------------------------------------------------------------- ??? [6] 100.0% [10] 300(0.3%) 0(0.0%) eval-errors .../herbie/master/src/points.rkt:179:0 for-loop [16] 100.0% --------------------------------------------------------------------------- run-improve47 [7] 3.7% ??? [6] 96.3% [11] 92932(92.1%) 0(0.0%) loop ...ghtlies/herbie/master/src/points.rkt:284:2 loop [17] 35.6% make-exacts-walkup [22] 33.5% loop [23] 30.9% --------------------------------------------------------------------------- run-improve47 [7] 100.0% [12] 5956(5.9%) 0(0.0%) simplify! .../herbie/master/src/mainloop.rkt:220:0 ??? [18] 100.0% --------------------------------------------------------------------------- run-improve47 [7] 100.0% [13] 978(1.0%) 0(0.0%) for-loop ...s/herbie/master/src/mainloop.rkt:359:4 run-iter! [19] 100.0% --------------------------------------------------------------------------- infer-double-representation [8] 100.0% [14] 378(0.4%) 0(0.0%) for-loop ...tlies/herbie/master/src/float.rkt:17:3 return/no-unsupplied [20] 100.0% --------------------------------------------------------------------------- errors-score [9] 100.0% [15] 312(0.3%) 312(0.3%) loop .../racket-7.0/collects/racket/list.rkt:559:2 --------------------------------------------------------------------------- eval-errors [10] 100.0% [16] 300(0.3%) 0(0.0%) for-loop ...ies/herbie/master/src/points.rkt:181:2 precision->real [21] 100.0% --------------------------------------------------------------------------- loop [11] 100.0% [17] 33044(32.8%) 0(0.0%) loop ...ghtlies/herbie/master/src/points.rkt:243:2 make-exacts-walkup [22] 52.4% loop [23] 47.6% --------------------------------------------------------------------------- simplify! [12] 100.0% [18] 5956(5.9%) 0(0.0%) ??? ...es/herbie/master/src/core/simplify.rkt:26:0 one-iter [29] 48.2% for-loop [24] 41.1% loop [26] 10.7% --------------------------------------------------------------------------- for-loop [13] 100.0% [19] 978(1.0%) 0(0.0%) run-iter! .../herbie/master/src/mainloop.rkt:323:0 localize! [25] 100.0% --------------------------------------------------------------------------- for-loop [14] 100.0% [20] 378(0.4%) 0(0.0%) return/no-unsupplied ...ct/private/arr-i.rkt:534:0 filter [27] 100.0% --------------------------------------------------------------------------- for-loop [16] 100.0% [21] 300(0.3%) 0(0.0%) precision->real ...e/master/src/programs.rkt:92:33 infer-representation [28] 100.0% --------------------------------------------------------------------------- loop [17] 35.7% loop [11] 64.3% [22] 48480(48.1%) 0(0.0%) make-exacts-walkup .../master/src/points.rkt:222:0 loop [54] 90.9% eval-prog [30] 9.1% --------------------------------------------------------------------------- loop [17] 35.4% loop [11] 64.6% [23] 44452(44.1%) 0(0.0%) loop ...ghtlies/herbie/master/src/points.rkt:226:4 loop [54] 100.0% --------------------------------------------------------------------------- ??? [18] 100.0% [24] 2450(2.4%) 0(0.0%) for-loop ...rbie/master/src/core/simplify.rkt:33:2 one-iter [29] 100.0% --------------------------------------------------------------------------- run-iter! [19] 100.0% [25] 978(1.0%) 0(0.0%) localize! .../herbie/master/src/mainloop.rkt:123:0 localize-error [31] 100.0% --------------------------------------------------------------------------- ??? [18] 100.0% [26] 636(0.6%) 0(0.0%) loop .../herbie/master/src/core/simplify.rkt:104:2 for-loop [32] 100.0% --------------------------------------------------------------------------- return/no-unsupplied [20] 100.0% [27] 378(0.4%) 378(0.4%) filter ...0/collects/racket/private/list.rkt:256:2 --------------------------------------------------------------------------- precision->real [21] 100.0% [28] 300(0.3%) 300(0.3%) infer-representation .../master/src/float.rkt:15:0 --------------------------------------------------------------------------- for-loop [24] 46.1% ??? [18] 53.9% [29] 5320(5.3%) 0(0.0%) one-iter ...rbie/master/src/core/simplify.rkt:65:0 find-matches [33] 58.9% for-loop [34] 23.5% for-loop [35] 17.6% --------------------------------------------------------------------------- make-exacts-walkup [22] 100.0% [30] 4424(4.4%) 0(0.0%) eval-prog ...s/herbie/master/src/programs.rkt:83:0 hash-ref! [61] 84.4% temp68_2 [36] 15.6% --------------------------------------------------------------------------- localize! [25] 100.0% [31] 978(1.0%) 0(0.0%) localize-error ...aster/src/core/localize.rkt:61:0 hash-ref! [61] 100.0% --------------------------------------------------------------------------- loop [26] 100.0% [32] 636(0.6%) 0(0.0%) for-loop ...bie/master/src/core/simplify.rkt:110:4 for-loop [37] 100.0% --------------------------------------------------------------------------- one-iter [29] 100.0% [33] 3132(3.1%) 0(0.0%) find-matches .../master/src/core/simplify.rkt:55:0 for-loop [38] 100.0% --------------------------------------------------------------------------- one-iter [29] 100.0% [34] 1252(1.2%) 0(0.0%) for-loop ...rbie/master/src/core/simplify.rkt:72:2 set-precompute! [39] 100.0% --------------------------------------------------------------------------- one-iter [29] 100.0% [35] 936(0.9%) 0(0.0%) for-loop ...rbie/master/src/core/simplify.rkt:67:2 for-loop [40] 100.0% --------------------------------------------------------------------------- eval-prog [30] 100.0% [36] 688(0.7%) 0(0.0%) temp68_2 (unknown source) compile16 [41] 100.0% --------------------------------------------------------------------------- for-loop [32] 100.0% [37] 636(0.6%) 336(0.3%) for-loop ...ie/master/src/core/simplify.rkt:114:16 for-loop [42] 47.2% --------------------------------------------------------------------------- for-loop [38] 45.2% find-matches [33] 54.8% [38] 3132(3.1%) 600(0.6%) for-loop ...rbie/master/src/core/simplify.rkt:57:8 for-loop [38] 45.2% match-e [43] 40.4% --------------------------------------------------------------------------- for-loop [34] 100.0% [39] 1252(1.2%) 0(0.0%) set-precompute! ...ster/src/core/simplify.rkt:79:0 for-loop [44] 100.0% --------------------------------------------------------------------------- for-loop [35] 100.0% [40] 936(0.9%) 0(0.0%) for-loop ...rbie/master/src/core/simplify.rkt:70:4 substitute-e [46] 67.3% dedup-children! [47] 32.7% --------------------------------------------------------------------------- temp68_2 [36] 100.0% [41] 688(0.7%) 0(0.0%) compile16 (unknown source) temp74_0 [45] 100.0% --------------------------------------------------------------------------- for-loop [37] 100.0% [42] 300(0.3%) 300(0.3%) for-loop ...ie/master/src/core/simplify.rkt:118:23 --------------------------------------------------------------------------- for-loop [59] 5.9% for-loop [38] 94.1% [43] 2532(2.5%) 0(0.0%) match-e .../herbie/master/src/core/ematch.rkt:39:0 for-loop [48] 87.0% make-sequence [52] 13.0% --------------------------------------------------------------------------- set-precompute! [39] 100.0% [44] 1252(1.2%) 0(0.0%) for-loop ...rbie/master/src/core/simplify.rkt:81:2 setfindf [49] 100.0% --------------------------------------------------------------------------- compile16 [41] 100.0% [45] 688(0.7%) 0(0.0%) temp74_0 (unknown source) expand-single [50] 100.0% --------------------------------------------------------------------------- for-loop [40] 100.0% [46] 630(0.6%) 0(0.0%) substitute-e ...ie/master/src/core/ematch.rkt:64:0 for-loop [51] 100.0% --------------------------------------------------------------------------- for-loop [40] 100.0% [47] 306(0.3%) 0(0.0%) dedup-children! ...master/src/core/enode.rkt:195:0 loop [53] 100.0% --------------------------------------------------------------------------- match-e [43] 100.0% [48] 2204(2.2%) 1612(1.6%) for-loop ...erbie/master/src/core/ematch.rkt:52:11 loop [54] 13.2% for-loop [59] 6.8% loop [58] 6.8% --------------------------------------------------------------------------- for-loop [44] 100.0% [49] 1252(1.2%) 0(0.0%) setfindf ...ies/herbie/master/src/common.rkt:142:0 for-loop [55] 100.0% --------------------------------------------------------------------------- temp74_0 [45] 100.0% [50] 688(0.7%) 0(0.0%) expand-single (unknown source) expand-capturing-lifts [56] 100.0% --------------------------------------------------------------------------- substitute-e [46] 100.0% [51] 630(0.6%) 0(0.0%) for-loop ...erbie/master/src/core/ematch.rkt:73:24 mk-enode! [57] 100.0% --------------------------------------------------------------------------- match-e [43] 100.0% [52] 328(0.3%) 328(0.3%) make-sequence ...ects/racket/private/for.rkt:528:2 --------------------------------------------------------------------------- dedup-children! [47] 33.3% loop [53] 66.7% [53] 306(0.3%) 306(0.3%) loop ...racket-7.0/collects/racket/list.rkt:434:17 loop [53] 66.7% --------------------------------------------------------------------------- map [74] 0.1% for-loop [48] 0.2% make-exacts-walkup [22] 0.2% loop [23] 1.2% ??? [2] 4.0% loop [54] 94.3% [54] 93188(92.4%) 292(0.3%) loop ...-7.0/collects/racket/private/map.rkt:40:19 loop [54] 94.3% hash-ref! [61] 4.0% composed [60] 1.4% ??? [78] 0.1% ??? [66] 0.0% --------------------------------------------------------------------------- setfindf [49] 100.0% [55] 1252(1.2%) 952(0.9%) for-loop ...ies/herbie/master/src/common.rkt:143:2 constant? [64] 24.0% --------------------------------------------------------------------------- expand-single [50] 100.0% [56] 688(0.7%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [62] 100.0% --------------------------------------------------------------------------- for-loop [51] 100.0% [57] 630(0.6%) 0(0.0%) mk-enode! ...erbie/master/src/core/egraph.rkt:97:0 hash-has-key? [63] 52.7% new-enode [65] 47.3% --------------------------------------------------------------------------- for-loop [48] 100.0% [58] 300(0.3%) 300(0.3%) loop ...cket-7.0/collects/racket/function.rkt:53:6 --------------------------------------------------------------------------- for-loop [48] 100.0% [59] 300(0.3%) 0(0.0%) for-loop ...erbie/master/src/core/ematch.rkt:58:37 match-e [43] 100.0% --------------------------------------------------------------------------- loop [54] 100.0% [60] 88116(87.4%) 0(0.0%) composed ...ollects/racket/private/list.rkt:321:16 ??? [66] 100.0% --------------------------------------------------------------------------- localize-error [31] 5.9% map [74] 14.9% eval-prog [30] 14.9% loop [54] 64.4% [61] 4714(4.7%) 654(0.6%) hash-ref! .../racket/private/more-scheme.rkt:376:2 ??? [2] 64.4% parse-loop30 [68] 20.7% bigfloat-hash [67] 11.8% --------------------------------------------------------------------------- expand-capturing-lifts [56] 100.0% [62] 688(0.7%) 0(0.0%) lambda-clause-expander (unknown source) loop [89] 53.5% finish-bodys [69] 46.5% --------------------------------------------------------------------------- mk-enode! [57] 100.0% [63] 332(0.3%) 332(0.3%) hash-has-key? ...ket/private/more-scheme.rkt:371:2 --------------------------------------------------------------------------- for-loop [55] 100.0% [64] 300(0.3%) 0(0.0%) constant? ...ie/master/src/syntax/syntax.rkt:719:0 value? [70] 100.0% --------------------------------------------------------------------------- mk-enode! [57] 100.0% [65] 298(0.3%) 0(0.0%) new-enode ...herbie/master/src/core/enode.rkt:94:0 type-of-enode-expr [71] 100.0% --------------------------------------------------------------------------- loop [54] 0.4% composed [60] 99.6% [66] 88508(87.8%) 392(0.4%) ??? ...htlies/herbie/master/src/programs.rkt:108:2 ??? [72] 99.6% --------------------------------------------------------------------------- hash-ref! [61] 100.0% [67] 3082(3.1%) 618(0.6%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:167:0 bfcanonicalize [73] 58.6% sig+exp->bigfloat [75] 21.3% --------------------------------------------------------------------------- hash-ref! [61] 100.0% [68] 978(1.0%) 0(0.0%) parse-loop30 ...s/racket/match/compiler.rkt:418:15 map [74] 100.0% --------------------------------------------------------------------------- lambda-clause-expander [62] 3.4% for-loop [76] 96.6% [69] 320(0.3%) 0(0.0%) finish-bodys (unknown source) for-loop [76] 100.0% --------------------------------------------------------------------------- constant? [64] 100.0% [70] 300(0.3%) 300(0.3%) value? .../herbie/master/src/syntax/types.rkt:18:0 --------------------------------------------------------------------------- new-enode [65] 100.0% [71] 298(0.3%) 0(0.0%) type-of-enode-expr ...ster/src/core/enode.rkt:66:0 table-ref [77] 100.0% --------------------------------------------------------------------------- ??? [66] 100.0% [72] 88116(87.4%) 680(0.7%) ??? ...t-7.0/collects/racket/private/kw.rkt:592:14 ??? [78] 98.8% ??? [79] 0.4% --------------------------------------------------------------------------- bigfloat-hash [67] 100.0% [73] 1806(1.8%) 1806(1.8%) bfcanonicalize .../private/bigfloat/mpfr.rkt:157:0 --------------------------------------------------------------------------- parse-loop30 [68] 100.0% [74] 978(1.0%) 0(0.0%) map ...t-7.0/collects/racket/private/map.rkt:35:13 hash-ref! [61] 71.6% loop [54] 22.8% loop [80] 5.6% --------------------------------------------------------------------------- bigfloat-hash [67] 100.0% [75] 658(0.7%) 0(0.0%) sig+exp->bigfloat ...ivate/bigfloat/mpfr.rkt:387:0 new-mpfr [99] 100.0% --------------------------------------------------------------------------- for-loop [76] 3.3% finish-bodys [69] 96.7% [76] 320(0.3%) 0(0.0%) for-loop (unknown source) finish-bodys [69] 93.3% for-loop [76] 3.3% syntax-e [81] 3.3% --------------------------------------------------------------------------- type-of-enode-expr [71] 100.0% [77] 298(0.3%) 298(0.3%) table-ref ...ies/herbie/master/src/common.rkt:45:0 --------------------------------------------------------------------------- loop [54] 0.7% ??? [72] 99.3% [78] 87706(87.0%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:361:33 bf-complex-mult [82] 88.4% bf-complex-add [83] 11.6% --------------------------------------------------------------------------- ??? [72] 100.0% [79] 382(0.4%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:357:33 ??? [84] 100.0% --------------------------------------------------------------------------- map [74] 2.2% loop [80] 97.8% [80] 326(0.3%) 0(0.0%) loop ...-7.0/collects/racket/private/map.rkt:54:19 loop [80] 97.8% ??? [85] 2.2% --------------------------------------------------------------------------- for-loop [76] 100.0% [81] 320(0.3%) 0(0.0%) syntax-e (unknown source) syntax-e/no-taint [86] 100.0% --------------------------------------------------------------------------- ??? [78] 100.0% [82] 77496(76.8%) 2948(2.9%) bf-complex-mult .../master/src/bigcomplex.rkt:30:0 bf* [87] 66.8% ??? [91] 26.2% ...higher-order.rkt:361:33 [88] 3.2% --------------------------------------------------------------------------- ??? [78] 100.0% [83] 10210(10.1%) 0(0.0%) bf-complex-add ...e/master/src/bigcomplex.rkt:23:0 ??? [91] 93.0% ...higher-order.rkt:361:33 [88] 7.0% --------------------------------------------------------------------------- ??? [79] 100.0% [84] 382(0.4%) 382(0.4%) ??? ...ects/racket/contract/private/guts.rkt:746:8 --------------------------------------------------------------------------- loop [80] 100.0% [85] 326(0.3%) 0(0.0%) ??? ...s/herbie/master/src/core/localize.rkt:52:36 ??? [90] 100.0% --------------------------------------------------------------------------- syntax-e [81] 100.0% [86] 320(0.3%) 0(0.0%) syntax-e/no-taint (unknown source) loop [89] 100.0% --------------------------------------------------------------------------- bf-complex-mult [82] 100.0% [87] 51778(51.3%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [91] 100.0% --------------------------------------------------------------------------- bf-complex-add [83] 22.4% bf-complex-mult [82] 77.6% [88] 3196(3.2%) 1732(1.7%) ...higher-order.rkt:361:33 (unknown source) ??? [92] 45.8% --------------------------------------------------------------------------- syntax-e/no-taint [86] 46.5% lambda-clause-expander [62] 53.5% [89] 688(0.7%) 0(0.0%) loop (unknown source) expand-id-application-form17 [93] 53.5% gf [94] 46.5% --------------------------------------------------------------------------- ??? [85] 100.0% [90] 326(0.3%) 0(0.0%) ??? ...htlies/herbie/master/src/interface.rkt:70:2 ??? [100] 100.0% --------------------------------------------------------------------------- bf-complex-add [83] 11.6% bf-complex-mult [82] 24.9% bf* [87] 63.5% [91] 81562(80.9%) 4140(4.1%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [95] 88.3% bfneg [96] 6.7% --------------------------------------------------------------------------- ...higher-order.rkt:361:33 [88] 100.0% [92] 1464(1.5%) 1464(1.5%) ??? ...cts/racket/contract/private/list.rkt:209:10 --------------------------------------------------------------------------- loop [89] 100.0% [93] 368(0.4%) 0(0.0%) expand-id-application-form17 (unknown source) resolve+shift28 [97] 100.0% --------------------------------------------------------------------------- loop [89] 100.0% [94] 320(0.3%) 0(0.0%) gf (unknown source) propagation-apply-shifted [98] 100.0% --------------------------------------------------------------------------- ??? [91] 100.0% [95] 71986(71.4%) 1930(1.9%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [99] 66.8% ??? [100] 30.6% --------------------------------------------------------------------------- ??? [91] 100.0% [96] 5436(5.4%) 0(0.0%) bfneg ...-lib/math/private/bigfloat/mpfr.rkt:618:4 new-mpfr [99] 87.6% ??? [100] 12.4% --------------------------------------------------------------------------- expand-id-application-form17 [93]100.0% [97] 368(0.4%) 0(0.0%) resolve+shift28 (unknown source) fallback-loop [101] 100.0% --------------------------------------------------------------------------- gf [94] 100.0% [98] 320(0.3%) 320(0.3%) propagation-apply-shifted (unknown source) --------------------------------------------------------------------------- sig+exp->bigfloat [75] 1.2% bfneg [96] 8.9% bfmul [95] 89.9% [99] 53480(53.0%) 53480(53.0%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 --------------------------------------------------------------------------- ??? [90] 1.4% bfneg [96] 2.9% bfmul [95] 95.6% [100] 22996(22.8%) 22996(22.8%) ??? /opt/racket-7.0/collects/ffi/unsafe.rkt:898:4 --------------------------------------------------------------------------- resolve+shift28 [97] 100.0% [101] 368(0.4%) 368(0.4%) fallback-loop (unknown source) ---------------------------------------------------------------------------