Profiling results ----------------- Total cpu time observed: 137266ms (out of 139912ms) Number of samples taken: 355 (once every 387ms) (Hiding functions with self<1.0% and local<2.0%: 2 of 87 hidden) =========================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee =========================================================================== ??? [8] 100.0% [1] 137266(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [5] 100.0% --------------------------------------------------------------------------- hash-ref! [35] 100.0% [2] 1322(1.0%) 0(0.0%) ??? ...htlies/herbie/master/src/programs.rkt:161:5 loop [28] 100.0% --------------------------------------------------------------------------- matcher [61] 100.0% [3] 330(0.2%) 0(0.0%) ??? ...s/herbie/master/src/core/matcher.rkt:130:11 for-loop [6] 100.0% --------------------------------------------------------------------------- ??? [8] 100.0% [4] 282(0.2%) 0(0.0%) errors ...tlies/herbie/master/src/points.rkt:205:0 for-loop [7] 100.0% --------------------------------------------------------------------------- profile-thunk16 [1] 100.0% [5] 137266(100.0%) 0(0.0%) run ...et-7.2/share/pkgs/profile-lib/main.rkt:39:2 ??? [8] 100.0% --------------------------------------------------------------------------- ??? [3] 100.0% [6] 330(0.2%) 0(0.0%) for-loop ...bie/master/src/core/matcher.rkt:132:15 matcher [61] 100.0% --------------------------------------------------------------------------- errors [4] 100.0% [7] 282(0.2%) 0(0.0%) for-loop ...ies/herbie/master/src/points.rkt:207:2 ??? [8] 100.0% --------------------------------------------------------------------------- for-loop [7] 0.1% run [5] 50.0% [8] 137266(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 50.0% loop [12] 47.5% run-improve47 [9] 2.2% oracle-error [10] 0.1% eval-errors [11] 0.1% errors [4] 0.1% ??? [38] 0.1% --------------------------------------------------------------------------- ??? [8] 100.0% [9] 6010(4.4%) 0(0.0%) run-improve47 ...bie/master/src/mainloop.rkt:345:0 loop [12] 73.1% for-loop [13] 21.8% simplify! [14] 5.1% --------------------------------------------------------------------------- ??? [8] 100.0% [10] 292(0.2%) 0(0.0%) oracle-error ...herbie/master/src/points.rkt:188:0 for-loop [15] 100.0% --------------------------------------------------------------------------- ??? [8] 100.0% [11] 290(0.2%) 0(0.0%) eval-errors .../herbie/master/src/points.rkt:179:0 for-loop [16] 100.0% --------------------------------------------------------------------------- run-improve47 [9] 3.3% ??? [8] 96.7% [12] 134788(98.2%) 0(0.0%) loop ...ghtlies/herbie/master/src/points.rkt:281:2 loop [17] 34.3% loop [22] 34.0% make-exacts-walkup [23] 28.8% for-loop [18] 2.9% --------------------------------------------------------------------------- run-improve47 [9] 100.0% [13] 1308(1.0%) 0(0.0%) for-loop ...s/herbie/master/src/mainloop.rkt:364:4 run-iter! [19] 100.0% --------------------------------------------------------------------------- run-improve47 [9] 100.0% [14] 306(0.2%) 0(0.0%) simplify! .../herbie/master/src/mainloop.rkt:223:0 ??? [20] 100.0% --------------------------------------------------------------------------- oracle-error [10] 100.0% [15] 292(0.2%) 0(0.0%) for-loop ...ies/herbie/master/src/points.rkt:189:2 loop [28] 100.0% --------------------------------------------------------------------------- eval-errors [11] 100.0% [16] 290(0.2%) 0(0.0%) for-loop ...ies/herbie/master/src/points.rkt:181:2 ordinary-value? [21] 100.0% --------------------------------------------------------------------------- loop [12] 100.0% [17] 46264(33.7%) 0(0.0%) loop ...ghtlies/herbie/master/src/points.rkt:244:2 loop [22] 52.4% make-exacts-walkup [23] 47.6% --------------------------------------------------------------------------- loop [12] 100.0% [18] 3906(2.8%) 364(0.3%) for-loop ...es/herbie/master/src/points.rkt:301:19 ??? [24] 90.7% --------------------------------------------------------------------------- for-loop [13] 100.0% [19] 1308(1.0%) 0(0.0%) run-iter! .../herbie/master/src/mainloop.rkt:326:0 localize! [25] 74.8% gen-rewrites! [26] 25.2% --------------------------------------------------------------------------- simplify! [14] 100.0% [20] 306(0.2%) 0(0.0%) ??? ...es/herbie/master/src/core/simplify.rkt:26:0 loop [27] 100.0% --------------------------------------------------------------------------- for-loop [16] 100.0% [21] 290(0.2%) 290(0.2%) ordinary-value? ...erbie/master/src/float.rkt:52:0 --------------------------------------------------------------------------- loop [17] 34.6% loop [12] 65.4% [22] 70048(51.0%) 0(0.0%) loop ...ghtlies/herbie/master/src/points.rkt:227:4 loop [28] 100.0% --------------------------------------------------------------------------- loop [17] 36.2% loop [12] 63.8% [23] 60834(44.3%) 0(0.0%) make-exacts-walkup .../master/src/points.rkt:222:0 loop [28] 95.8% eval-prog [29] 4.2% --------------------------------------------------------------------------- for-loop [18] 100.0% [24] 3542(2.6%) 0(0.0%) ??? ...ightlies/herbie/master/src/points.rkt:106:2 ??? [59] 91.5% compose [33] 8.5% --------------------------------------------------------------------------- run-iter! [19] 100.0% [25] 978(0.7%) 0(0.0%) localize! .../herbie/master/src/mainloop.rkt:126:0 localize-error [30] 100.0% --------------------------------------------------------------------------- run-iter! [19] 100.0% [26] 330(0.2%) 0(0.0%) gen-rewrites! ...bie/master/src/mainloop.rkt:182:0 for-loop [31] 100.0% --------------------------------------------------------------------------- ??? [20] 100.0% [27] 306(0.2%) 0(0.0%) loop ...herbie/master/src/core/extraction.rkt:25:2 extractor-cost [32] 100.0% --------------------------------------------------------------------------- map [46] 0.0% for-loop [15] 0.2% ??? [2] 1.0% make-exacts-walkup [23] 1.1% loop [22] 1.2% loop [28] 96.5% [28] 130596(95.1%) 0(0.0%) loop ...-7.2/collects/racket/private/map.rkt:40:19 loop [28] 96.5% composed [34] 2.2% hash-ref! [35] 1.0% ??? [38] 0.1% ??? [58] 0.0% --------------------------------------------------------------------------- make-exacts-walkup [23] 100.0% [29] 2570(1.9%) 0(0.0%) eval-prog ...s/herbie/master/src/programs.rkt:83:0 hash-ref! [35] 63.5% loop [36] 36.5% --------------------------------------------------------------------------- localize! [25] 100.0% [30] 978(0.7%) 0(0.0%) localize-error ...aster/src/core/localize.rkt:66:0 hash-ref! [35] 100.0% --------------------------------------------------------------------------- gen-rewrites! [26] 100.0% [31] 330(0.2%) 0(0.0%) for-loop .../herbie/master/src/mainloop.rkt:190:11 rewrite-expression-head20 [37] 100.0% --------------------------------------------------------------------------- loop [27] 100.0% [32] 306(0.2%) 306(0.2%) extractor-cost ...ter/src/core/extraction.rkt:16:0 --------------------------------------------------------------------------- ??? [24] 100.0% [33] 302(0.2%) 302(0.2%) compose ...collects/racket/private/list.rkt:385:12 --------------------------------------------------------------------------- loop [28] 100.0% [34] 122916(89.5%) 0(0.0%) composed ...ollects/racket/private/list.rkt:321:16 ??? [38] 100.0% --------------------------------------------------------------------------- localize-error [30] 8.8% eval-prog [29] 27.5% map [46] 28.7% loop [28] 35.0% [35] 2610(1.9%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 parse-loop30 [40] 37.5% ??? [2] 35.0% bigfloat-hash [39] 27.5% --------------------------------------------------------------------------- loop [36] 12.2% expand-capturing-lifts [41] 12.2% apply-transformer52 [63] 15.6% eval-prog [29] 60.0% [36] 938(0.7%) 0(0.0%) loop (unknown source) expand-capturing-lifts [41] 60.0% core-form-sym [44] 15.6% loop [36] 12.2% for-loop [42] 12.2% --------------------------------------------------------------------------- for-loop [31] 100.0% [37] 330(0.2%) 0(0.0%) rewrite-expression-head20 ...core/matcher.rkt:87:0 rewriter [43] 100.0% --------------------------------------------------------------------------- ??? [8] 0.2% loop [28] 4.2% composed [34] 95.4% [38] 128886(93.9%) 906(0.7%) ??? ...htlies/herbie/master/src/programs.rkt:115:2 ??? [45] 90.1% ??? [59] 9.0% --------------------------------------------------------------------------- hash-ref! [35] 100.0% [39] 1632(1.2%) 0(0.0%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:167:0 bfcanonicalize [47] 57.2% sig+exp->bigfloat [48] 42.8% --------------------------------------------------------------------------- hash-ref! [35] 100.0% [40] 978(0.7%) 0(0.0%) parse-loop30 ...s/racket/match/compiler.rkt:418:15 map [46] 93.7% table-ref [54] 6.3% --------------------------------------------------------------------------- loop [36] 100.0% [41] 938(0.7%) 0(0.0%) expand-capturing-lifts (unknown source) loop [36] 36.7% expand-id-application-form17 [55] 32.2% dispatch-transformer41 [57] 31.1% --------------------------------------------------------------------------- temp37_0 [50] 33.3% run-module-instance!125 [51] 33.3% loop [36] 33.3% [42] 344(0.3%) 0(0.0%) for-loop (unknown source) run-module-instance!125 [51] 33.3% temp37_0 [50] 33.3% module-path-index-resolve [49] 33.3% --------------------------------------------------------------------------- rewrite-expression-head20 [37] 33.3% matcher [61] 66.7% [43] 330(0.2%) 0(0.0%) rewriter ...erbie/master/src/core/matcher.rkt:90:2 for-loop [53] 66.7% *rules* [52] 33.3% --------------------------------------------------------------------------- loop [36] 100.0% [44] 292(0.2%) 0(0.0%) core-form-sym (unknown source) resolve+shift28 [56] 100.0% --------------------------------------------------------------------------- ??? [38] 100.0% [45] 116156(84.6%) 666(0.5%) ??? ...t-7.2/collects/racket/private/kw.rkt:594:14 ??? [58] 99.1% ??? [59] 0.3% --------------------------------------------------------------------------- parse-loop30 [40] 100.0% [46] 978(0.7%) 0(0.0%) map ...t-7.2/collects/racket/private/map.rkt:35:13 hash-ref! [35] 82.9% loop [28] 17.1% --------------------------------------------------------------------------- bigfloat-hash [39] 100.0% [47] 934(0.7%) 934(0.7%) bfcanonicalize .../private/bigfloat/mpfr.rkt:157:0 --------------------------------------------------------------------------- bigfloat-hash [39] 100.0% [48] 698(0.5%) 0(0.0%) sig+exp->bigfloat ...ivate/bigfloat/mpfr.rkt:387:0 new-mpfr [82] 100.0% --------------------------------------------------------------------------- for-loop [42] 100.0% [49] 344(0.3%) 0(0.0%) module-path-index-resolve (unknown source) standard-module-name-resolver [60]100.0% --------------------------------------------------------------------------- for-loop [42] 100.0% [50] 344(0.3%) 0(0.0%) temp37_0 (unknown source) for-loop [42] 100.0% --------------------------------------------------------------------------- for-loop [42] 100.0% [51] 344(0.3%) 0(0.0%) run-module-instance!125 (unknown source) for-loop [42] 100.0% --------------------------------------------------------------------------- rewriter [43] 100.0% [52] 330(0.2%) 330(0.2%) *rules* ...erbie/master/src/syntax/rules.rkt:666:0 --------------------------------------------------------------------------- rewriter [43] 100.0% [53] 330(0.2%) 0(0.0%) for-loop ...erbie/master/src/core/matcher.rkt:92:4 matcher [61] 100.0% --------------------------------------------------------------------------- parse-loop30 [40] 100.0% [54] 308(0.2%) 308(0.2%) table-ref ...ies/herbie/master/src/common.rkt:45:0 --------------------------------------------------------------------------- expand-capturing-lifts [41] 100.0% [55] 302(0.2%) 0(0.0%) expand-id-application-form17 (unknown source) binding-lookup52 [62] 100.0% --------------------------------------------------------------------------- core-form-sym [44] 100.0% [56] 292(0.2%) 292(0.2%) resolve+shift28 (unknown source) --------------------------------------------------------------------------- expand-capturing-lifts [41] 100.0% [57] 292(0.2%) 0(0.0%) dispatch-transformer41 (unknown source) apply-transformer52 [63] 100.0% --------------------------------------------------------------------------- loop [28] 0.6% ??? [45] 99.4% [58] 115804(84.4%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:364:33 bf-complex-exp [64] 68.8% bf-complex-div [65] 26.6% bf-complex-add [67] 2.7% bf-complex-neg [68] 1.9% --------------------------------------------------------------------------- ??? [45] 2.4% ??? [24] 21.4% ??? [38] 76.2% [59] 15140(11.0%) 5244(3.8%) ??? ...tract/private/arrow-higher-order.rkt:360:33 bf [66] 51.3% sample-multi-bounded [69] 14.0% --------------------------------------------------------------------------- module-path-index-resolve [49] 100.0% [60] 344(0.3%) 344(0.3%) standard-module-name-resolver (unknown source) --------------------------------------------------------------------------- for-loop [6] 50.0% for-loop [53] 50.0% [61] 330(0.2%) 0(0.0%) matcher ...erbie/master/src/core/matcher.rkt:118:2 ??? [3] 50.0% rewriter [43] 50.0% --------------------------------------------------------------------------- expand-id-application-form17 [55] 100.0% [62] 302(0.2%) 302(0.2%) binding-lookup52 (unknown source) --------------------------------------------------------------------------- dispatch-transformer41 [57] 100.0% [63] 292(0.2%) 0(0.0%) apply-transformer52 (unknown source) loop [36] 100.0% --------------------------------------------------------------------------- ??? [58] 100.0% [64] 79670(58.0%) 1092(0.8%) bf-complex-exp ...e/master/src/bigcomplex.rkt:37:0 ??? [73] 92.5% bf* [70] 5.7% ...higher-order.rkt:364:33 [71] 0.5% --------------------------------------------------------------------------- ??? [58] 100.0% [65] 30766(22.4%) 2534(1.8%) bf-complex-div ...e/master/src/bigcomplex.rkt:54:0 ??? [73] 46.6% bf* [70] 39.3% ...higher-order.rkt:364:33 [71] 5.9% --------------------------------------------------------------------------- ??? [59] 100.0% [66] 7772(5.7%) 682(0.5%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [82] 77.1% ??? [83] 14.1% --------------------------------------------------------------------------- ??? [58] 100.0% [67] 3116(2.3%) 484(0.4%) bf-complex-add ...e/master/src/bigcomplex.rkt:23:0 ??? [73] 84.5% --------------------------------------------------------------------------- ??? [58] 100.0% [68] 2252(1.6%) 0(0.0%) bf-complex-neg ...e/master/src/bigcomplex.rkt:20:0 ??? [73] 100.0% --------------------------------------------------------------------------- ??? [59] 100.0% [69] 2124(1.5%) 622(0.5%) sample-multi-bounded ...master/src/points.rkt:15:0 random-ranges [72] 70.7% --------------------------------------------------------------------------- bf-complex-exp [64] 27.4% bf-complex-div [65] 72.6% [70] 16650(12.1%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [73] 100.0% --------------------------------------------------------------------------- bf-complex-exp [64] 16.6% bf-complex-div [65] 83.4% [71] 2176(1.6%) 690(0.5%) ...higher-order.rkt:364:33 (unknown source) ??? [74] 68.3% --------------------------------------------------------------------------- sample-multi-bounded [69] 100.0% [72] 1502(1.1%) 328(0.2%) random-ranges ...erbie/master/src/common.rkt:183:0 cons/c [75] 39.5% dict? [76] 19.7% build--> [77] 18.9% --------------------------------------------------------------------------- bf-complex-neg [68] 2.1% bf-complex-add [67] 2.4% bf-complex-div [65] 13.1% bf* [70] 15.2% bf-complex-exp [64] 67.3% [73] 109518(79.8%) 70272(51.2%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfadd [78] 23.2% bfcos [79] 12.6% --------------------------------------------------------------------------- ...higher-order.rkt:364:33 [71] 100.0% [74] 1486(1.1%) 1164(0.8%) ??? ...cts/racket/contract/private/list.rkt:209:10 ??? [81] 21.7% --------------------------------------------------------------------------- random-ranges [72] 100.0% [75] 594(0.4%) 248(0.2%) cons/c .../racket/contract/private/guts.rkt:504:15 coerce-contract [80] 58.2% --------------------------------------------------------------------------- random-ranges [72] 100.0% [76] 296(0.2%) 296(0.2%) dict? ...ollects/racket/private/generic.rkt:146:11 --------------------------------------------------------------------------- random-ranges [72] 100.0% [77] 284(0.2%) 0(0.0%) build--> ...act/private/arrow-val-first.rkt:1088:0 coerce-contract [80] 100.0% --------------------------------------------------------------------------- ??? [73] 100.0% [78] 25410(18.5%) 334(0.2%) bfadd ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [82] 62.6% ??? [83] 36.1% --------------------------------------------------------------------------- ??? [73] 100.0% [79] 13836(10.1%) 362(0.3%) bfcos ...-lib/math/private/bigfloat/mpfr.rkt:618:4 new-mpfr [82] 67.4% ??? [83] 30.0% --------------------------------------------------------------------------- build--> [77] 45.1% cons/c [75] 54.9% [80] 630(0.5%) 0(0.0%) coerce-contract ...contract/private/guts.rkt:308:0 coerce-contract/f [84] 100.0% --------------------------------------------------------------------------- ??? [74] 100.0% [81] 322(0.2%) 322(0.2%) ??? ...ects/racket/contract/private/guts.rkt:772:8 --------------------------------------------------------------------------- sig+exp->bigfloat [48] 2.2% bf [66] 18.8% bfcos [79] 29.2% bfadd [78] 49.8% [82] 31924(23.3%) 31924(23.3%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 --------------------------------------------------------------------------- bf [66] 7.6% bfcos [79] 28.8% bfadd [78] 63.6% [83] 14414(10.5%) 14414(10.5%) ??? /opt/racket-7.2/collects/ffi/unsafe.rkt:905:4 --------------------------------------------------------------------------- coerce-contract [80] 100.0% [84] 630(0.5%) 0(0.0%) coerce-contract/f ...ntract/private/guts.rkt:373:0 coerce-simple-value [85] 100.0% --------------------------------------------------------------------------- coerce-contract/f [84] 100.0% [85] 630(0.5%) 630(0.5%) coerce-simple-value ...ract/private/guts.rkt:386:0 ---------------------------------------------------------------------------