Profiling results ----------------- Total cpu time observed: 78644ms (out of 79236ms) Number of samples taken: 188 (once every 418ms) ============================================================================ Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ============================================================================ run [15] 50.0% [1] 78644(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [9] 50.0% loop [22] 24.3% prepare-points [23] 22.7% run-improve39 [12] 3.1% ---------------------------------------------------------------------------- loop [38] 100.0% [2] 69530(88.4%) 672(0.9%) ??? ...tlies/herbie/develop/src/programs.rkt:129:2 ??? [10] 91.3% bf [11] 7.7% ---------------------------------------------------------------------------- hash-ref! [46] 100.0% [3] 2692(3.4%) 0(0.0%) ??? ...tlies/herbie/develop/src/programs.rkt:153:5 loop [38] 100.0% ---------------------------------------------------------------------------- hash-ref! [46] 100.0% [4] 620(0.8%) 0(0.0%) f8 ...et/collects/racket/match/compiler.rkt:507:40 map [13] 100.0% ---------------------------------------------------------------------------- hash-ref! [46] 100.0% [5] 620(0.8%) 0(0.0%) parse-loop14 ...s/racket/match/compiler.rkt:418:15 loop [38] 100.0% ---------------------------------------------------------------------------- loop [97] 100.0% [6] 322(0.4%) 0(0.0%) expand-id-application-form17 (unknown source) syntax-e/no-taint [14] 100.0% ---------------------------------------------------------------------------- loop [21] 100.0% [7] 320(0.4%) 0(0.0%) ??? .../herbie/develop/src/core/localize.rkt:43:32 ??? [45] 100.0% ---------------------------------------------------------------------------- for-loop [103] 100.0% [8] 312(0.4%) 0(0.0%) expand-identifier (unknown source) resolve+shift28 [100] 100.0% ---------------------------------------------------------------------------- ??? [1] 100.0% [9] 78644(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [15] 100.0% ---------------------------------------------------------------------------- ??? [2] 100.0% [10] 63478(80.7%) 4784(6.1%) ??? ...acket/collects/racket/private/kw.rkt:592:14 ??? [44] 85.8% ??? [16] 5.1% ??? [45] 1.1% ??? [48] 0.5% ---------------------------------------------------------------------------- ??? [2] 100.0% [11] 5380(6.8%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [86] 93.8% ??? [87] 6.2% ---------------------------------------------------------------------------- ??? [1] 100.0% [12] 4808(6.1%) 0(0.0%) run-improve39 ...ie/develop/src/mainloop.rkt:286:0 setup-prog!32 [17] 58.8% for-loop [18] 25.9% ??? [19] 7.9% for-loop [20] 7.5% ---------------------------------------------------------------------------- f8 [4] 100.0% [13] 620(0.8%) 0(0.0%) map ...acket/collects/racket/private/map.rkt:35:13 hash-ref! [46] 74.2% loop [21] 25.8% ---------------------------------------------------------------------------- expand-id-application-form17 [6] 100.0% [14] 322(0.4%) 0(0.0%) syntax-e/no-taint (unknown source) loop [97] 100.0% ---------------------------------------------------------------------------- profile-thunk16 [9] 100.0% [15] 78644(100.0%) 0(0.0%) run ...racket/share/pkgs/profile-lib/main.rkt:39:2 ??? [1] 100.0% ---------------------------------------------------------------------------- ??? [10] 100.0% [16] 3220(4.1%) 3220(4.1%) ??? ...rivate/unconstrained-domain-arrow.rkt:40:13 ---------------------------------------------------------------------------- run-improve39 [12] 100.0% [17] 2826(3.6%) 0(0.0%) setup-prog!32 ...bie/develop/src/mainloop.rkt:63:0 prepare-points [23] 56.0% loop [22] 44.0% ---------------------------------------------------------------------------- run-improve39 [12] 100.0% [18] 1244(1.6%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:303:8 run-iter! [24] 100.0% ---------------------------------------------------------------------------- run-improve39 [12] 100.0% [19] 378(0.5%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:388:18 for-loop [25] 100.0% ---------------------------------------------------------------------------- run-improve39 [12] 100.0% [20] 360(0.5%) 0(0.0%) for-loop ...herbie/develop/src/mainloop.rkt:298:29 ??? [58] 100.0% ---------------------------------------------------------------------------- map [13] 4.3% loop [21] 95.7% [21] 320(0.4%) 0(0.0%) loop ...cket/collects/racket/private/map.rkt:54:19 loop [21] 95.7% ??? [7] 4.3% ---------------------------------------------------------------------------- setup-prog!32 [17] 3.2% ??? [1] 96.8% [22] 39452(50.2%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:180:2 loop [26] 36.0% make-exacts* [33] 32.2% loop [32] 30.8% for-loop [27] 1.0% ---------------------------------------------------------------------------- setup-prog!32 [17] 4.3% ??? [1] 95.7% [23] 37210(47.3%) 0(0.0%) prepare-points ...bie/develop/src/points.rkt:156:0 loop [26] 35.9% make-exacts* [33] 31.3% loop [32] 31.1% for-loop [27] 1.7% ---------------------------------------------------------------------------- for-loop [18] 100.0% [24] 1244(1.6%) 0(0.0%) run-iter! ...herbie/develop/src/mainloop.rkt:267:0 localize-error [28] 49.8% simplify! [30] 25.6% gen-rewrites! [31] 24.6% ---------------------------------------------------------------------------- ??? [19] 100.0% [25] 378(0.5%) 0(0.0%) for-loop ...ie/develop/src/core/alt-table.rkt:51:2 atab-add-altn [29] 100.0% ---------------------------------------------------------------------------- prepare-points [23] 48.5% loop [22] 51.5% [26] 27564(35.0%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:124:2 loop [32] 58.3% make-exacts* [33] 41.7% ---------------------------------------------------------------------------- loop [22] 39.6% prepare-points [23] 60.4% [27] 1026(1.3%) 0(0.0%) for-loop ...s/herbie/develop/src/points.rkt:195:19 ??? [34] 100.0% ---------------------------------------------------------------------------- run-iter! [24] 100.0% [28] 620(0.8%) 0(0.0%) localize-error ...velop/src/core/localize.rkt:47:0 hash-ref! [46] 100.0% ---------------------------------------------------------------------------- for-loop [25] 100.0% [29] 378(0.5%) 0(0.0%) atab-add-altn ...elop/src/core/alt-table.rkt:210:0 best-and-tied-at-points [35] 100.0% ---------------------------------------------------------------------------- run-iter! [24] 100.0% [30] 318(0.4%) 0(0.0%) simplify! ...herbie/develop/src/mainloop.rkt:181:0 for-loop [36] 100.0% ---------------------------------------------------------------------------- run-iter! [24] 100.0% [31] 306(0.4%) 0(0.0%) gen-rewrites! ...ie/develop/src/mainloop.rkt:164:0 for-loop [37] 100.0% ---------------------------------------------------------------------------- prepare-points [23] 29.1% loop [22] 30.5% loop [26] 40.4% [32] 39796(50.6%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:107:4 loop [38] 100.0% ---------------------------------------------------------------------------- loop [26] 32.1% prepare-points [23] 32.5% loop [22] 35.5% [33] 35840(45.6%) 0(0.0%) make-exacts* ...erbie/develop/src/points.rkt:104:0 loop [38] 88.3% eval-prog [39] 11.7% ---------------------------------------------------------------------------- for-loop [27] 100.0% [34] 1026(1.3%) 0(0.0%) ??? ...ghtlies/herbie/develop/src/points.rkt:172:7 for-loop [40] 100.0% ---------------------------------------------------------------------------- atab-add-altn [29] 100.0% [35] 378(0.5%) 0(0.0%) best-and-tied-at-points ...ore/alt-table.rkt:122:0 for-loop [41] 100.0% ---------------------------------------------------------------------------- simplify! [30] 100.0% [36] 318(0.4%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:185:6 for-loop [42] 100.0% ---------------------------------------------------------------------------- gen-rewrites! [31] 100.0% [37] 306(0.4%) 0(0.0%) for-loop ...herbie/develop/src/mainloop.rkt:169:11 ??? [43] 100.0% ---------------------------------------------------------------------------- make-exacts* [33] 0.4% parse-loop14 [5] 0.8% loop [32] 3.0% ??? [3] 3.6% loop [38] 92.3% [38] 74772(95.1%) 650(0.8%) loop ...cket/collects/racket/private/map.rkt:40:19 loop [38] 92.3% hash-ref! [46] 3.9% ??? [2] 3.3% ??? [44] 0.4% ??? [45] 0.0% ??? [48] 0.0% ---------------------------------------------------------------------------- make-exacts* [33] 100.0% [39] 4176(5.3%) 0(0.0%) eval-prog ...herbie/develop/src/programs.rkt:109:0 hash-ref! [46] 64.5% temp68_2 [47] 35.5% ---------------------------------------------------------------------------- ??? [34] 100.0% [40] 1026(1.3%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:173:9 ??? [45] 100.0% ---------------------------------------------------------------------------- best-and-tied-at-points [35] 100.0% [41] 378(0.5%) 378(0.5%) for-loop .../develop/src/core/alt-table.rkt:124:16 ---------------------------------------------------------------------------- for-loop [36] 100.0% [42] 318(0.4%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:209:8 ??? [45] 100.0% ---------------------------------------------------------------------------- for-loop [37] 100.0% [43] 306(0.4%) 0(0.0%) ??? ...ies/herbie/develop/src/alternative.rkt:60:0 rewriter [49] 100.0% ---------------------------------------------------------------------------- loop [38] 0.5% ??? [10] 99.5% [44] 54784(69.7%) 346(0.4%) ??? ...tract/private/arrow-higher-order.rkt:361:33 bf-complex-exp [50] 52.7% bf-complex-div [51] 38.8% bf-complex-add [52] 5.3% bf-complex-neg [56] 2.6% ---------------------------------------------------------------------------- location-do [59] 4.6% for-loop [42] 4.6% ??? [7] 9.2% ??? [10] 19.5% for-loop [40] 29.6% loop [38] 32.5% [45] 3470(4.4%) 324(0.4%) ??? ...tract/private/arrow-higher-order.rkt:357:33 ->flonum [55] 41.7% sample-multi-bounded [57] 29.6% ??? [82] 10.2% location-do [59] 4.6% ??? [58] 4.6% ---------------------------------------------------------------------------- map [13] 6.9% localize-error [28] 6.9% eval-prog [39] 19.8% loop [38] 66.3% [46] 3312(4.2%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 ??? [3] 63.3% bigfloat-hash [53] 18.0% parse-loop14 [5] 9.4% f8 [4] 9.4% ---------------------------------------------------------------------------- eval-prog [39] 100.0% [47] 1484(1.9%) 0(0.0%) temp68_2 (unknown source) compile16 [54] 100.0% ---------------------------------------------------------------------------- ??? [10] 40.2% loop [38] 59.8% [48] 776(1.0%) 464(0.6%) ??? ...ects/racket/contract/private/prop.rkt:519:4 ??? [60] 40.2% ---------------------------------------------------------------------------- matcher [70] 50.0% ??? [43] 50.0% [49] 306(0.4%) 0(0.0%) rewriter ...bie/develop/src/core/matcher.rkt:131:2 for-loop [61] 100.0% ---------------------------------------------------------------------------- ??? [44] 100.0% [50] 28878(36.7%) 1578(2.0%) bf-complex-exp .../develop/src/bigcomplex.rkt:41:0 ??? [71] 82.6% bf* [62] 11.9% ---------------------------------------------------------------------------- ??? [44] 100.0% [51] 21234(27.0%) 1326(1.7%) bf-complex-div .../develop/src/bigcomplex.rkt:58:0 ??? [71] 51.0% bf* [62] 37.6% ...higher-order.rkt:361:33 [64] 5.2% ---------------------------------------------------------------------------- ??? [44] 100.0% [52] 2888(3.7%) 0(0.0%) bf-complex-add .../develop/src/bigcomplex.rkt:24:0 ??? [71] 100.0% ---------------------------------------------------------------------------- hash-ref! [46] 100.0% [53] 2380(3.0%) 0(0.0%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:167:0 sig+exp->bigfloat [63] 100.0% ---------------------------------------------------------------------------- temp68_2 [47] 100.0% [54] 1484(1.9%) 0(0.0%) compile16 (unknown source) temp74_0 [65] 100.0% ---------------------------------------------------------------------------- ??? [45] 100.0% [55] 1448(1.8%) 664(0.8%) ->flonum ...s/herbie/develop/src/programs.rkt:36:0 ??? [87] 32.0% ??? [82] 22.1% ---------------------------------------------------------------------------- ??? [44] 100.0% [56] 1438(1.8%) 0(0.0%) bf-complex-neg .../develop/src/bigcomplex.rkt:21:0 ??? [71] 69.0% ...higher-order.rkt:361:33 [64] 31.0% ---------------------------------------------------------------------------- ??? [45] 100.0% [57] 1026(1.3%) 718(0.9%) sample-multi-bounded ...evelop/src/points.rkt:43:0 loop [68] 30.0% ---------------------------------------------------------------------------- ??? [45] 46.9% for-loop [20] 53.1% [58] 678(0.9%) 0(0.0%) ??? ...s/herbie/develop/src/core/simplify.rkt:31:0 mk-egraph [66] 53.1% iterate-egraph!7 [67] 46.9% ---------------------------------------------------------------------------- ??? [45] 100.0% [59] 318(0.4%) 0(0.0%) location-do ...erbie/develop/src/programs.rkt:90:0 ??? [45] 100.0% ---------------------------------------------------------------------------- ??? [48] 100.0% [60] 312(0.4%) 312(0.4%) ??? ...lects/racket/contract/private/orc.rkt:83:14 ---------------------------------------------------------------------------- rewriter [49] 100.0% [61] 306(0.4%) 0(0.0%) for-loop ...ie/develop/src/core/matcher.rkt:134:10 matcher [70] 50.0% type-of [69] 50.0% ---------------------------------------------------------------------------- bf-complex-exp [50] 30.1% bf-complex-div [51] 69.9% [62] 11418(14.5%) 648(0.8%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [71] 94.3% ---------------------------------------------------------------------------- bigfloat-hash [53] 100.0% [63] 2380(3.0%) 0(0.0%) sig+exp->bigfloat ...ivate/bigfloat/mpfr.rkt:387:0 new-mpfr [86] 100.0% ---------------------------------------------------------------------------- bf-complex-neg [56] 28.7% bf-complex-div [51] 71.3% [64] 1556(2.0%) 744(0.9%) ...higher-order.rkt:361:33 (unknown source) ??? [73] 52.2% ---------------------------------------------------------------------------- compile16 [54] 100.0% [65] 1484(1.9%) 0(0.0%) temp74_0 (unknown source) expand-single [72] 100.0% ---------------------------------------------------------------------------- ??? [58] 100.0% [66] 360(0.5%) 0(0.0%) mk-egraph ...bie/develop/src/core/egraph.rkt:131:0 mk-enode-rec! [74] 100.0% ---------------------------------------------------------------------------- ??? [58] 100.0% [67] 318(0.4%) 0(0.0%) iterate-egraph!7 ...lop/src/core/simplify.rkt:55:0 one-iter [75] 100.0% ---------------------------------------------------------------------------- sample-multi-bounded [57] 100.0% [68] 308(0.4%) 0(0.0%) loop ...ghtlies/herbie/develop/src/points.rkt:65:6 random-exp [76] 100.0% ---------------------------------------------------------------------------- for-loop [61] 100.0% [69] 306(0.4%) 0(0.0%) type-of .../herbie/develop/src/type-check.rkt:34:0 ??? [77] 100.0% ---------------------------------------------------------------------------- for-loop [78] 50.0% for-loop [61] 50.0% [70] 306(0.4%) 0(0.0%) matcher ...rbie/develop/src/core/matcher.rkt:168:2 for-loop [78] 50.0% rewriter [49] 50.0% ---------------------------------------------------------------------------- bf-complex-neg [56] 2.0% bf-complex-add [52] 5.9% bf* [62] 21.8% bf-complex-div [51] 21.9% bf-complex-exp [50] 48.4% [71] 49330(62.7%) 17082(21.7%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfadd [79] 35.5% bfneg [80] 29.8% ---------------------------------------------------------------------------- temp74_0 [65] 100.0% [72] 1484(1.9%) 0(0.0%) expand-single (unknown source) expand-capturing-lifts [81] 100.0% ---------------------------------------------------------------------------- ...higher-order.rkt:361:33 [64] 100.0% [73] 812(1.0%) 358(0.5%) ??? ...cts/racket/contract/private/list.rkt:209:10 ??? [82] 55.9% ---------------------------------------------------------------------------- mk-egraph [66] 25.0% mk-enode-rec! [74] 75.0% [74] 360(0.5%) 0(0.0%) mk-enode-rec! ...develop/src/core/egraph.rkt:122:0 mk-enode-rec! [74] 75.0% mk-enode! [83] 25.0% ---------------------------------------------------------------------------- iterate-egraph!7 [67] 100.0% [75] 318(0.4%) 0(0.0%) one-iter ...bie/develop/src/core/simplify.rkt:66:0 for-loop [84] 100.0% ---------------------------------------------------------------------------- loop [68] 100.0% [76] 308(0.4%) 308(0.4%) random-exp .../herbie/develop/src/common.rkt:243:0 ---------------------------------------------------------------------------- type-of [69] 100.0% [77] 306(0.4%) 0(0.0%) ??? ...ects/racket/contract/private/hash.rkt:227:4 contract-pos/neg-doubling/proc [85]100.0% ---------------------------------------------------------------------------- matcher [70] 100.0% [78] 306(0.4%) 0(0.0%) for-loop ...ie/develop/src/core/matcher.rkt:184:21 matcher [70] 100.0% ---------------------------------------------------------------------------- ??? [71] 100.0% [79] 17524(22.3%) 970(1.2%) bfadd ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [86] 75.0% ??? [87] 19.5% ---------------------------------------------------------------------------- ??? [71] 100.0% [80] 14724(18.7%) 370(0.5%) bfneg ...-lib/math/private/bigfloat/mpfr.rkt:618:4 new-mpfr [86] 69.8% ??? [87] 27.7% ---------------------------------------------------------------------------- expand-single [72] 100.0% [81] 1484(1.9%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [88] 63.1% dispatch-transformer41 [89] 36.9% ---------------------------------------------------------------------------- ->flonum [55] 28.4% ??? [45] 31.4% ??? [73] 40.2% [82] 1128(1.4%) 1128(1.4%) ??? ...ects/racket/contract/private/guts.rkt:746:8 ---------------------------------------------------------------------------- mk-enode-rec! [74] 100.0% [83] 360(0.5%) 0(0.0%) mk-enode! ...bie/develop/src/core/egraph.rkt:102:0 new-enode [90] 100.0% ---------------------------------------------------------------------------- for-loop [84] 50.0% one-iter [75] 50.0% [84] 318(0.4%) 318(0.4%) for-loop ...ie/develop/src/core/simplify.rkt:76:12 for-loop [84] 50.0% ---------------------------------------------------------------------------- ??? [77] 100.0% [85] 306(0.4%) 306(0.4%) contract-pos/neg-doubling/proc ...e/guts.rkt:956:0 ---------------------------------------------------------------------------- sig+exp->bigfloat [63] 7.7% bf [11] 16.4% bfneg [80] 33.3% bfadd [79] 42.6% [86] 30842(39.2%) 30842(39.2%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ---------------------------------------------------------------------------- bf [11] 4.0% ->flonum [55] 5.6% bfadd [79] 41.2% bfneg [80] 49.2% [87] 8290(10.5%) 8290(10.5%) ??? /home/p92/racket/collects/ffi/unsafe.rkt:898:4 ---------------------------------------------------------------------------- expand-capturing-lifts [81] 100.0% [88] 936(1.2%) 0(0.0%) lambda-clause-expander (unknown source) finish-bodys [92] 67.7% loop [97] 32.3% ---------------------------------------------------------------------------- loop [97] 35.5% expand-capturing-lifts [81] 64.5% [89] 850(1.1%) 0(0.0%) dispatch-transformer41 (unknown source) apply-transformer52 [91] 100.0% ---------------------------------------------------------------------------- mk-enode! [83] 100.0% [90] 360(0.5%) 360(0.5%) new-enode ...erbie/develop/src/core/enode.rkt:95:0 ---------------------------------------------------------------------------- dispatch-transformer41 [89] 100.0% [91] 850(1.1%) 0(0.0%) apply-transformer52 (unknown source) loop [97] 64.5% apply-transformer-in-context [93] 35.5% ---------------------------------------------------------------------------- lambda-clause-expander [88] 14.3% for-loop [103] 85.7% [92] 634(0.8%) 0(0.0%) finish-bodys (unknown source) for-loop [103] 100.0% ---------------------------------------------------------------------------- apply-transformer52 [91] 100.0% [93] 302(0.4%) 0(0.0%) apply-transformer-in-context (unknown source) go [94] 100.0% ---------------------------------------------------------------------------- apply-transformer-in-context [93] 100.0% [94] 302(0.4%) 0(0.0%) go .../collects/racket/private/qq-and-or.rkt:50:20 loop [95] 100.0% ---------------------------------------------------------------------------- go [94] 100.0% [95] 302(0.4%) 0(0.0%) loop ...llects/racket/private/qq-and-or.rkt:101:61 datum->syntax6 [96] 100.0% ---------------------------------------------------------------------------- loop [95] 100.0% [96] 302(0.4%) 0(0.0%) datum->syntax6 (unknown source) loop [97] 100.0% ---------------------------------------------------------------------------- datum->syntax6 [96] 12.9% lambda-clause-expander [88] 12.9% syntax-e/no-taint [14] 13.7% for-loop [103] 13.7% apply-transformer52 [91] 46.8% [97] 1172(1.5%) 302(0.4%) loop (unknown source) core-form-sym [98] 46.8% expand-id-application-form17 [6] 13.7% gf [99] 13.7% dispatch-transformer41 [89] 12.9% ---------------------------------------------------------------------------- loop [97] 100.0% [98] 548(0.7%) 0(0.0%) core-form-sym (unknown source) resolve+shift28 [100] 100.0% ---------------------------------------------------------------------------- loop [97] 100.0% [99] 322(0.4%) 0(0.0%) gf (unknown source) propagation-apply [101] 100.0% ---------------------------------------------------------------------------- expand-identifier [8] 36.3% core-form-sym [98] 63.7% [100] 860(1.1%) 0(0.0%) resolve+shift28 (unknown source) fallback-loop [102] 100.0% ---------------------------------------------------------------------------- gf [99] 100.0% [101] 322(0.4%) 322(0.4%) propagation-apply (unknown source) ---------------------------------------------------------------------------- resolve+shift28 [100] 100.0% [102] 860(1.1%) 312(0.4%) fallback-loop (unknown source) for-loop [103] 63.7% ---------------------------------------------------------------------------- fallback-loop [102] 23.2% for-loop [103] 29.0% finish-bodys [92] 47.8% [103] 1182(1.5%) 548(0.7%) for-loop (unknown source) finish-bodys [92] 40.9% for-loop [103] 29.0% loop [97] 3.9% expand-identifier [8] 2.9% ----------------------------------------------------------------------------