Profiling results ----------------- Total cpu time observed: 132282ms (out of 135988ms) Number of samples taken: 328 (once every 403ms) (Hiding functions with self<1.0% and local<2.0%: 2 of 97 hidden) ========================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ========================================================================== ??? [20] 100.0% [1] 132282(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [11] 100.0% -------------------------------------------------------------------------- ??? [20] 100.0% [2] 39614(29.9%) 0(0.0%) run-improve39 ...ie/develop/src/mainloop.rkt:287:0 for-loop [12] 88.0% setup-prog!32 [14] 11.1% for-loop [16] 0.8% -------------------------------------------------------------------------- map-enodes [30] 0.9% loop [38] 99.1% [3] 32670(24.7%) 0(0.0%) set-precompute! ...lop/src/core/simplify.rkt:140:0 for-loop [13] 100.0% -------------------------------------------------------------------------- hash-ref! [45] 100.0% [4] 3118(2.4%) 0(0.0%) ??? ...tlies/herbie/develop/src/programs.rkt:153:5 loop [38] 100.0% -------------------------------------------------------------------------- loop [90] 100.0% [5] 362(0.3%) 0(0.0%) core-form-sym (unknown source) resolve+shift28 [93] 100.0% -------------------------------------------------------------------------- hash-ref! [45] 100.0% [6] 334(0.3%) 0(0.0%) parse-loop14 ...s/racket/match/compiler.rkt:418:15 loop [38] 75.0% loop [15] 25.0% -------------------------------------------------------------------------- hash-ref! [45] 100.0% [7] 334(0.3%) 0(0.0%) f8 ...et/collects/racket/match/compiler.rkt:507:40 map [17] 100.0% -------------------------------------------------------------------------- ??? [20] 100.0% [8] 332(0.3%) 0(0.0%) oracle-error ...erbie/develop/src/points.rkt:227:0 for-loop [18] 100.0% -------------------------------------------------------------------------- match-e [40] 100.0% [9] 308(0.2%) 0(0.0%) for-loop ...rbie/develop/src/core/ematch.rkt:59:11 for-loop [19] 100.0% -------------------------------------------------------------------------- for-loop [95] 100.0% [10] 308(0.2%) 0(0.0%) expand-implicit (unknown source) resolve+shift28 [93] 100.0% -------------------------------------------------------------------------- profile-thunk16 [1] 100.0% [11] 132282(100.0%) 0(0.0%) run ...racket/share/pkgs/profile-lib/main.rkt:39:2 ??? [20] 100.0% -------------------------------------------------------------------------- run-improve39 [2] 100.0% [12] 34872(26.4%) 0(0.0%) for-loop ...herbie/develop/src/mainloop.rkt:298:19 ??? [21] 100.0% -------------------------------------------------------------------------- set-precompute! [3] 100.0% [13] 32670(24.7%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:142:2 reduce-to-new! [22] 54.9% update-leader! [51] 41.9% eval-const-expr [23] 3.1% -------------------------------------------------------------------------- run-improve39 [2] 100.0% [14] 4408(3.3%) 0(0.0%) setup-prog!32 ...bie/develop/src/mainloop.rkt:63:0 loop [25] 100.0% -------------------------------------------------------------------------- parse-loop14 [6] 1.7% loop [15] 98.3% [15] 334(0.3%) 334(0.3%) loop ...cket/collects/racket/private/map.rkt:54:19 loop [15] 98.3% -------------------------------------------------------------------------- run-improve39 [2] 100.0% [16] 334(0.3%) 0(0.0%) for-loop ...herbie/develop/src/mainloop.rkt:304:10 run-iter! [24] 100.0% -------------------------------------------------------------------------- f8 [7] 100.0% [17] 334(0.3%) 0(0.0%) map ...acket/collects/racket/private/map.rkt:35:13 hash-ref! [45] 100.0% -------------------------------------------------------------------------- oracle-error [8] 100.0% [18] 332(0.3%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:228:2 loop [38] 100.0% -------------------------------------------------------------------------- for-loop [9] 100.0% [19] 308(0.2%) 0(0.0%) for-loop ...rbie/develop/src/core/ematch.rkt:65:37 match-e [40] 100.0% -------------------------------------------------------------------------- run [11] 50.0% [20] 132282(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 50.0% loop [25] 34.6% run-improve39 [2] 15.0% oracle-error [8] 0.1% eval-errors [28] 0.1% -------------------------------------------------------------------------- for-loop [12] 99.0% [21] 35242(26.6%) 0(0.0%) ??? ...s/herbie/develop/src/core/simplify.rkt:31:0 iterate-egraph!7 [26] 100.0% -------------------------------------------------------------------------- for-loop [13] 100.0% [22] 17946(13.6%) 0(0.0%) reduce-to-new! ...evelop/src/core/egraph.rkt:337:0 merge-egraph-nodes! [44] 98.2% hash-update! [71] 1.8% -------------------------------------------------------------------------- for-loop [13] 100.0% [23] 1026(0.8%) 0(0.0%) eval-const-expr .../develop/src/programs.rkt:132:0 eval-prog [39] 100.0% -------------------------------------------------------------------------- for-loop [16] 100.0% [24] 334(0.3%) 0(0.0%) run-iter! ...herbie/develop/src/mainloop.rkt:268:0 localize-error [27] 100.0% -------------------------------------------------------------------------- setup-prog!32 [14] 4.6% ??? [20] 95.4% [25] 96054(72.6%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:167:2 loop [34] 62.6% loop [29] 34.6% make-exacts* [35] 1.5% make-exacts [32] 1.4% -------------------------------------------------------------------------- ??? [21] 100.0% [26] 35242(26.6%) 0(0.0%) iterate-egraph!7 ...lop/src/core/simplify.rkt:55:0 map-enodes [30] 92.7% one-iter [31] 7.3% -------------------------------------------------------------------------- run-iter! [24] 100.0% [27] 334(0.3%) 0(0.0%) localize-error ...velop/src/core/localize.rkt:51:0 hash-ref! [45] 100.0% -------------------------------------------------------------------------- ??? [20] 100.0% [28] 320(0.2%) 0(0.0%) eval-errors ...herbie/develop/src/points.rkt:208:0 for-loop [33] 100.0% -------------------------------------------------------------------------- loop [25] 100.0% [29] 33214(25.1%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:125:2 loop [34] 57.5% make-exacts* [35] 38.3% eval-prog [39] 4.3% -------------------------------------------------------------------------- iterate-egraph!7 [26] 100.0% [30] 32670(24.7%) 0(0.0%) map-enodes ...ie/develop/src/core/egraph.rkt:140:0 loop [38] 99.1% set-precompute! [3] 0.9% -------------------------------------------------------------------------- iterate-egraph!7 [26] 100.0% [31] 2572(1.9%) 0(0.0%) one-iter ...bie/develop/src/core/simplify.rkt:66:0 for-loop [36] 73.2% for-loop [37] 26.8% -------------------------------------------------------------------------- loop [25] 100.0% [32] 1340(1.0%) 0(0.0%) make-exacts ...herbie/develop/src/points.rkt:123:0 make-exacts* [35] 74.2% loop [34] 25.8% -------------------------------------------------------------------------- eval-errors [28] 100.0% [33] 320(0.2%) 320(0.2%) for-loop ...es/herbie/develop/src/points.rkt:210:2 -------------------------------------------------------------------------- make-exacts [32] 0.4% loop [29] 24.0% loop [25] 75.6% [34] 79522(60.1%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:108:4 loop [38] 100.0% -------------------------------------------------------------------------- make-exacts [32] 6.6% loop [25] 9.3% loop [29] 84.1% [35] 15112(11.4%) 0(0.0%) make-exacts* ...erbie/develop/src/points.rkt:105:0 loop [38] 84.0% eval-prog [39] 16.0% -------------------------------------------------------------------------- one-iter [31] 100.0% [36] 1882(1.4%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:113:2 update-leader! [51] 51.5% for-loop [41] 32.1% match-e [40] 16.4% -------------------------------------------------------------------------- for-loop [37] 50.0% one-iter [31] 50.0% [37] 690(0.5%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:76:12 for-loop [37] 50.0% ??? [42] 26.8% match-e [40] 23.2% -------------------------------------------------------------------------- make-exacts* [35] 0.1% for-loop [18] 0.3% parse-loop14 [6] 0.3% map-enodes [30] 0.4% loop [34] 0.9% ??? [4] 2.4% loop [38] 95.6% [38] 128366(97.0%) 0(0.0%) loop ...cket/collects/racket/private/map.rkt:40:19 loop [38] 95.6% hash-ref! [45] 2.7% ??? [43] 1.0% set-precompute! [3] 0.4% ??? [60] 0.3% -------------------------------------------------------------------------- eval-const-expr [23] 21.1% loop [29] 29.2% make-exacts* [35] 49.7% [39] 4860(3.7%) 0(0.0%) eval-prog ...herbie/develop/src/programs.rkt:109:0 hash-ref! [45] 64.2% temp68_2 [46] 21.5% loop [90] 7.4% inductor [48] 6.9% -------------------------------------------------------------------------- for-loop [19] 24.5% for-loop [36] 24.5% for-loop [37] 51.0% [40] 628(0.5%) 628(0.5%) match-e ...herbie/develop/src/core/ematch.rkt:46:0 for-loop [9] 24.5% -------------------------------------------------------------------------- for-loop [36] 100.0% [41] 604(0.5%) 0(0.0%) for-loop ...bie/develop/src/core/simplify.rkt:98:4 mk-enode! [49] 50.7% merge-egraph-nodes! [44] 49.3% -------------------------------------------------------------------------- for-loop [37] 100.0% [42] 370(0.3%) 0(0.0%) ??? ...ies/herbie/develop/src/core/ematch.rkt:50:5 for-loop [47] 100.0% -------------------------------------------------------------------------- loop [38] 100.0% [43] 91174(68.9%) 0(0.0%) ??? ...tlies/herbie/develop/src/programs.rkt:129:2 ??? [50] 100.0% -------------------------------------------------------------------------- for-loop [41] 1.7% for-loop [53] 2.9% reduce-to-new! [22] 95.4% [44] 17912(13.5%) 0(0.0%) merge-egraph-nodes! ...p/src/core/egraph.rkt:153:0 update-leader! [51] 97.1% for-loop [53] 2.9% -------------------------------------------------------------------------- map [17] 1.9% localize-error [27] 1.9% eval-prog [39] 18.0% loop [38] 78.1% [45] 3452(2.6%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 ??? [4] 72.3% bigfloat-hash [52] 18.0% parse-loop14 [6] 7.7% f8 [7] 1.9% -------------------------------------------------------------------------- eval-prog [39] 100.0% [46] 1044(0.8%) 0(0.0%) temp68_2 (unknown source) compile16 [54] 100.0% -------------------------------------------------------------------------- ??? [42] 100.0% [47] 370(0.3%) 370(0.3%) for-loop ...erbie/develop/src/core/ematch.rkt:51:7 -------------------------------------------------------------------------- eval-prog [39] 100.0% [48] 336(0.3%) 0(0.0%) inductor .../herbie/develop/src/programs.rkt:119:4 ??? [55] 100.0% -------------------------------------------------------------------------- for-loop [41] 100.0% [49] 306(0.2%) 0(0.0%) mk-enode! ...bie/develop/src/core/egraph.rkt:102:0 hash-has-key? [56] 100.0% -------------------------------------------------------------------------- ??? [43] 100.0% [50] 91174(68.9%) 298(0.2%) ??? ...acket/collects/racket/private/kw.rkt:592:14 ??? [57] 99.3% ??? [60] 0.3% -------------------------------------------------------------------------- for-loop [36] 3.0% for-loop [13] 42.0% merge-egraph-nodes! [44] 55.0% [51] 32580(24.6%) 0(0.0%) update-leader! ...evelop/src/core/egraph.rkt:218:0 for-loop [58] 99.1% -------------------------------------------------------------------------- hash-ref! [45] 100.0% [52] 3118(2.4%) 0(0.0%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:167:0 bfcanonicalize [59] 54.7% sig+exp->bigfloat [61] 45.3% -------------------------------------------------------------------------- merge-egraph-nodes! [44] 100.0% [53] 1054(0.8%) 0(0.0%) for-loop ...bie/develop/src/core/egraph.rkt:201:10 merge-egraph-nodes! [44] 100.0% -------------------------------------------------------------------------- temp68_2 [46] 100.0% [54] 1044(0.8%) 0(0.0%) compile16 (unknown source) temp74_0 [62] 100.0% -------------------------------------------------------------------------- inductor [48] 100.0% [55] 336(0.3%) 336(0.3%) ??? ...contract/private/arrow-val-first.rkt:388:18 -------------------------------------------------------------------------- mk-enode! [49] 100.0% [56] 306(0.2%) 306(0.2%) hash-has-key? ...ket/private/more-scheme.rkt:371:2 -------------------------------------------------------------------------- ??? [50] 100.0% [57] 90578(68.5%) 1392(1.1%) ??? ...tract/private/arrow-higher-order.rkt:361:33 bf-complex-mult [63] 94.1% bf-complex-add [65] 4.4% -------------------------------------------------------------------------- update-leader! [51] 100.0% [58] 32282(24.4%) 344(0.3%) for-loop ...rbie/develop/src/core/egraph.rkt:223:6 for-loop [64] 98.9% -------------------------------------------------------------------------- bigfloat-hash [52] 100.0% [59] 1706(1.3%) 1358(1.0%) bfcanonicalize .../private/bigfloat/mpfr.rkt:157:0 bigfloat->sig+exp [68] 20.4% -------------------------------------------------------------------------- ??? [50] 17.8% loop [38] 82.2% [60] 1676(1.3%) 298(0.2%) ??? ...tract/private/arrow-higher-order.rkt:357:33 ->flonum [66] 82.2% -------------------------------------------------------------------------- bigfloat-hash [52] 100.0% [61] 1412(1.1%) 0(0.0%) sig+exp->bigfloat ...ivate/bigfloat/mpfr.rkt:387:0 new-mpfr [87] 76.3% integer->mpz [69] 23.7% -------------------------------------------------------------------------- compile16 [54] 100.0% [62] 1044(0.8%) 0(0.0%) temp74_0 (unknown source) expand-single [67] 100.0% -------------------------------------------------------------------------- ??? [57] 100.0% [63] 85232(64.4%) 4948(3.7%) bf-complex-mult ...develop/src/bigcomplex.rkt:31:0 bf* [70] 57.6% ??? [74] 33.9% ...higher-order.rkt:361:33 [72] 2.7% -------------------------------------------------------------------------- for-loop [58] 100.0% [64] 31938(24.1%) 0(0.0%) for-loop ...rbie/develop/src/core/egraph.rkt:224:8 hash-update! [71] 100.0% -------------------------------------------------------------------------- ??? [57] 100.0% [65] 3954(3.0%) 0(0.0%) bf-complex-add .../develop/src/bigcomplex.rkt:24:0 ??? [74] 89.4% ...higher-order.rkt:361:33 [72] 10.6% -------------------------------------------------------------------------- ??? [60] 100.0% [66] 1378(1.0%) 1378(1.0%) ->flonum ...s/herbie/develop/src/programs.rkt:36:0 -------------------------------------------------------------------------- temp74_0 [62] 100.0% [67] 1044(0.8%) 0(0.0%) expand-single (unknown source) expand-capturing-lifts [73] 100.0% -------------------------------------------------------------------------- bfcanonicalize [59] 100.0% [68] 348(0.3%) 348(0.3%) bigfloat->sig+exp ...ivate/bigfloat/mpfr.rkt:372:0 -------------------------------------------------------------------------- sig+exp->bigfloat [61] 100.0% [69] 334(0.3%) 334(0.3%) integer->mpz ...math/private/bigfloat/gmp.rkt:87:0 -------------------------------------------------------------------------- bf-complex-mult [63] 100.0% [70] 49120(37.1%) 346(0.3%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [74] 99.3% -------------------------------------------------------------------------- reduce-to-new! [22] 1.0% for-loop [64] 99.0% [71] 32270(24.4%) 0(0.0%) hash-update! ...cket/private/more-scheme.rkt:365:4 ??? [75] 99.0% ??? [79] 1.0% -------------------------------------------------------------------------- bf-complex-add [65] 15.5% bf-complex-mult [63] 84.5% [72] 2714(2.1%) 962(0.7%) ...higher-order.rkt:361:33 (unknown source) ??? [76] 64.6% -------------------------------------------------------------------------- loop [90] 25.7% expand-single [67] 74.3% [73] 1406(1.1%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [77] 74.3% dispatch-transformer41 [78] 25.7% -------------------------------------------------------------------------- bf-complex-add [65] 4.4% bf-complex-mult [63] 35.6% bf* [70] 60.1% [74] 81178(61.4%) 7792(5.9%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [80] 78.5% bfneg [82] 11.9% -------------------------------------------------------------------------- hash-update! [71] 100.0% [75] 31938(24.1%) 334(0.3%) ??? ...s/herbie/develop/src/core/egraph.rkt:226:24 for-loop [81] 99.0% -------------------------------------------------------------------------- ...higher-order.rkt:361:33 [72] 100.0% [76] 1752(1.3%) 310(0.2%) ??? ...cts/racket/contract/private/list.rkt:209:10 ??? [83] 82.3% -------------------------------------------------------------------------- expand-capturing-lifts [73] 100.0% [77] 1044(0.8%) 356(0.3%) lambda-clause-expander (unknown source) finish-bodys [84] 65.9% -------------------------------------------------------------------------- expand-capturing-lifts [73] 100.0% [78] 362(0.3%) 0(0.0%) dispatch-transformer41 (unknown source) apply-transformer52 [85] 100.0% -------------------------------------------------------------------------- hash-update! [71] 100.0% [79] 332(0.3%) 0(0.0%) ??? ...s/herbie/develop/src/core/egraph.rkt:349:16 for-loop [86] 100.0% -------------------------------------------------------------------------- ??? [74] 100.0% [80] 63736(48.2%) 2006(1.5%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [87] 63.0% ??? [88] 33.9% -------------------------------------------------------------------------- ??? [75] 100.0% [81] 31604(23.9%) 26516(20.0%) for-loop ...bie/develop/src/core/egraph.rkt:227:26 update-en-expr [89] 16.1% -------------------------------------------------------------------------- ??? [74] 100.0% [82] 9650(7.3%) 0(0.0%) bfneg ...-lib/math/private/bigfloat/mpfr.rkt:618:4 new-mpfr [87] 51.0% ??? [88] 49.0% -------------------------------------------------------------------------- ??? [76] 100.0% [83] 1442(1.1%) 1442(1.1%) ??? ...ects/racket/contract/private/guts.rkt:746:8 -------------------------------------------------------------------------- lambda-clause-expander [77] 22.8% for-loop [95] 77.2% [84] 688(0.5%) 0(0.0%) finish-bodys (unknown source) for-loop [95] 100.0% -------------------------------------------------------------------------- dispatch-transformer41 [78] 100.0% [85] 362(0.3%) 0(0.0%) apply-transformer52 (unknown source) loop [90] 100.0% -------------------------------------------------------------------------- ??? [79] 100.0% [86] 332(0.3%) 332(0.3%) for-loop ...bie/develop/src/core/egraph.rkt:350:18 -------------------------------------------------------------------------- sig+exp->bigfloat [61] 2.3% bfneg [82] 10.7% bfmul [80] 87.0% [87] 46130(34.9%) 46130(34.9%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 -------------------------------------------------------------------------- bfneg [82] 18.0% bfmul [80] 82.0% [88] 26328(19.9%) 26328(19.9%) ??? /home/p92/racket/collects/ffi/unsafe.rkt:898:4 -------------------------------------------------------------------------- for-loop [81] 100.0% [89] 5088(3.8%) 2020(1.5%) update-en-expr ...evelop/src/core/egraph.rkt:212:0 for-loop [91] 60.3% -------------------------------------------------------------------------- apply-transformer52 [85] 24.4% eval-prog [39] 24.4% for-loop [95] 51.2% [90] 742(0.6%) 0(0.0%) loop (unknown source) expand-id-application-form17 [92] 51.2% expand-capturing-lifts [73] 24.4% core-form-sym [5] 24.4% -------------------------------------------------------------------------- update-en-expr [89] 100.0% [91] 3068(2.3%) 3068(2.3%) for-loop ...rbie/develop/src/core/egraph.rkt:214:6 -------------------------------------------------------------------------- loop [90] 100.0% [92] 380(0.3%) 0(0.0%) expand-id-application-form17 (unknown source) resolve+shift28 [93] 100.0% -------------------------------------------------------------------------- expand-implicit [10] 29.3% core-form-sym [5] 34.5% expand-id-application-form17 [92] 36.2% [93] 1050(0.8%) 0(0.0%) resolve+shift28 (unknown source) fallback-loop [94] 100.0% -------------------------------------------------------------------------- resolve+shift28 [93] 100.0% [94] 1050(0.8%) 0(0.0%) fallback-loop (unknown source) for-loop [95] 100.0% -------------------------------------------------------------------------- fallback-loop [94] 27.1% for-loop [95] 27.1% finish-bodys [84] 45.7% [95] 1050(0.8%) 1050(0.8%) for-loop (unknown source) finish-bodys [84] 35.9% for-loop [95] 27.1% expand-implicit [10] 5.9% loop [90] 4.0% --------------------------------------------------------------------------