Profiling results ----------------- Total cpu time observed: 123468ms (out of 126840ms) Number of samples taken: 309 (once every 400ms) (Hiding functions with self<1.0% and local<2.0%: 3 of 103 hidden) =========================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee =========================================================================== ??? [15] 100.0% [1] 123468(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [7] 100.0% --------------------------------------------------------------------------- loop [47] 100.0% [2] 88778(71.9%) 0(0.0%) composed ...ollects/racket/private/list.rkt:321:16 ??? [8] 100.0% --------------------------------------------------------------------------- ??? [15] 100.0% [3] 32732(26.5%) 0(0.0%) run-improve39 ...ie/develop/src/mainloop.rkt:322:0 for-loop [9] 90.8% setup-prog!32 [11] 7.2% for-loop [13] 2.0% --------------------------------------------------------------------------- loop [47] 100.0% [4] 27474(22.3%) 0(0.0%) set-precompute! ...lop/src/core/simplify.rkt:151:0 for-loop [10] 100.0% --------------------------------------------------------------------------- for-loop [82] 100.0% [5] 668(0.5%) 0(0.0%) syntax-e (unknown source) syntax-e/no-taint [12] 100.0% --------------------------------------------------------------------------- ??? [15] 100.0% [6] 634(0.5%) 0(0.0%) oracle-error ...erbie/develop/src/points.rkt:311:0 for-loop [14] 100.0% --------------------------------------------------------------------------- profile-thunk16 [1] 100.0% [7] 123468(100.0%) 0(0.0%) run ...racket/share/pkgs/profile-lib/main.rkt:39:2 ??? [15] 100.0% --------------------------------------------------------------------------- loop [47] 0.3% for-loop [91] 1.7% composed [2] 97.9% [8] 90664(73.4%) 0(0.0%) ??? ...tlies/herbie/develop/src/programs.rkt:124:2 ??? [16] 100.0% --------------------------------------------------------------------------- run-improve39 [3] 100.0% [9] 29722(24.1%) 0(0.0%) for-loop ...herbie/develop/src/mainloop.rkt:333:19 ??? [17] 100.0% --------------------------------------------------------------------------- set-precompute! [4] 100.0% [10] 27474(22.3%) 334(0.3%) for-loop ...ie/develop/src/core/simplify.rkt:153:2 reduce-to-new! [18] 51.4% update-leader! [62] 42.5% eval-const-expr [19] 4.9% --------------------------------------------------------------------------- run-improve39 [3] 100.0% [11] 2344(1.9%) 0(0.0%) setup-prog!32 ...bie/develop/src/mainloop.rkt:63:0 loop [21] 85.8% ??? [33] 14.2% --------------------------------------------------------------------------- syntax-e [5] 100.0% [12] 668(0.5%) 0(0.0%) syntax-e/no-taint (unknown source) loop [92] 100.0% --------------------------------------------------------------------------- run-improve39 [3] 100.0% [13] 666(0.5%) 0(0.0%) for-loop ...herbie/develop/src/mainloop.rkt:339:10 run-iter! [20] 100.0% --------------------------------------------------------------------------- oracle-error [6] 100.0% [14] 634(0.5%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:312:2 loop [47] 100.0% --------------------------------------------------------------------------- run [7] 50.0% [15] 123468(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 50.0% loop [21] 35.7% run-improve39 [3] 13.3% eval-errors [86] 0.8% oracle-error [6] 0.3% --------------------------------------------------------------------------- ??? [8] 100.0% [16] 90664(73.4%) 2186(1.8%) ??? ...acket/collects/racket/private/kw.rkt:592:14 ??? [22] 91.6% bf [24] 3.8% ??? [64] 2.2% --------------------------------------------------------------------------- for-loop [9] 100.0% [17] 29722(24.1%) 0(0.0%) ??? ...s/herbie/develop/src/core/simplify.rkt:31:0 iterate-egraph!7 [23] 100.0% --------------------------------------------------------------------------- for-loop [10] 100.0% [18] 14120(11.4%) 0(0.0%) reduce-to-new! ...evelop/src/core/egraph.rkt:334:0 merge-egraph-nodes! [54] 97.5% hash-update! [81] 2.5% --------------------------------------------------------------------------- for-loop [10] 100.0% [19] 1352(1.1%) 0(0.0%) eval-const-expr .../develop/src/programs.rkt:127:0 eval-prog [25] 100.0% --------------------------------------------------------------------------- for-loop [13] 100.0% [20] 666(0.5%) 0(0.0%) run-iter! ...herbie/develop/src/mainloop.rkt:303:0 localize-error [26] 52.0% choose-best-alt! [27] 48.0% --------------------------------------------------------------------------- setup-prog!32 [11] 2.2% ??? [15] 97.8% [21] 90182(73.0%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:242:2 loop [29] 36.0% loop [35] 33.2% make-exacts-walkup [36] 30.7% --------------------------------------------------------------------------- ??? [16] 100.0% [22] 83034(67.3%) 2660(2.2%) ??? ...tract/private/arrow-higher-order.rkt:361:33 bf-complex-mult [28] 85.8% bf-complex-add [31] 11.0% --------------------------------------------------------------------------- ??? [17] 100.0% [23] 29722(24.1%) 0(0.0%) iterate-egraph!7 ...lop/src/core/simplify.rkt:61:0 one-iter [30] 100.0% --------------------------------------------------------------------------- ??? [16] 100.0% [24] 3462(2.8%) 1724(1.4%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [61] 31.5% ??? [63] 18.7% --------------------------------------------------------------------------- make-alt-table [45] 19.8% eval-const-expr [19] 80.2% [25] 1686(1.4%) 0(0.0%) eval-prog ...herbie/develop/src/programs.rkt:108:0 temp68_2 [32] 100.0% --------------------------------------------------------------------------- run-iter! [20] 100.0% [26] 346(0.3%) 0(0.0%) localize-error ...velop/src/core/localize.rkt:51:0 hash-ref! [34] 100.0% --------------------------------------------------------------------------- run-iter! [20] 100.0% [27] 320(0.3%) 0(0.0%) choose-best-alt! ...develop/src/mainloop.rkt:115:0 ??? [33] 100.0% --------------------------------------------------------------------------- ??? [22] 100.0% [28] 71228(57.7%) 3120(2.5%) bf-complex-mult ...develop/src/bigcomplex.rkt:30:0 bf* [37] 56.4% ??? [48] 35.3% ...higher-order.rkt:361:33 [39] 3.9% --------------------------------------------------------------------------- loop [21] 100.0% [29] 32508(26.3%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:125:2 loop [35] 53.5% make-exacts-walkup [36] 46.5% --------------------------------------------------------------------------- iterate-egraph!7 [23] 100.0% [30] 29722(24.1%) 0(0.0%) one-iter ...bie/develop/src/core/simplify.rkt:72:0 map-enodes [38] 92.4% for-loop [40] 6.4% for-loop [44] 1.1% --------------------------------------------------------------------------- ??? [22] 100.0% [31] 9146(7.4%) 348(0.3%) bf-complex-add .../develop/src/bigcomplex.rkt:23:0 ??? [48] 96.2% --------------------------------------------------------------------------- eval-prog [25] 100.0% [32] 1686(1.4%) 0(0.0%) temp68_2 (unknown source) compile16 [41] 100.0% --------------------------------------------------------------------------- choose-best-alt! [27] 48.9% setup-prog!32 [11] 51.1% [33] 654(0.5%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:388:18 make-alt-table [45] 51.1% ??? [46] 48.9% --------------------------------------------------------------------------- loop [47] 33.3% map [58] 33.3% localize-error [26] 33.3% [34] 346(0.3%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 parse-loop14 [43] 66.7% f8 [42] 33.3% --------------------------------------------------------------------------- loop [29] 36.8% loop [21] 63.2% [35] 47362(38.4%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:108:4 loop [47] 100.0% --------------------------------------------------------------------------- loop [29] 35.3% loop [21] 64.7% [36] 42820(34.7%) 0(0.0%) make-exacts-walkup ...develop/src/points.rkt:105:0 loop [47] 99.2% --------------------------------------------------------------------------- bf-complex-mult [28] 100.0% [37] 40192(32.6%) 368(0.3%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [48] 99.1% --------------------------------------------------------------------------- one-iter [30] 100.0% [38] 27474(22.3%) 0(0.0%) map-enodes ...ie/develop/src/core/egraph.rkt:140:0 loop [47] 100.0% --------------------------------------------------------------------------- bf-complex-mult [28] 100.0% [39] 2804(2.3%) 826(0.7%) ...higher-order.rkt:361:33 (unknown source) ??? [49] 70.5% --------------------------------------------------------------------------- one-iter [30] 100.0% [40] 1914(1.6%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:123:2 for-loop [51] 66.4% match-e [52] 17.6% update-leader! [62] 16.1% --------------------------------------------------------------------------- temp68_2 [32] 100.0% [41] 1686(1.4%) 0(0.0%) compile16 (unknown source) temp74_0 [50] 100.0% --------------------------------------------------------------------------- hash-ref! [34] 100.0% [42] 346(0.3%) 0(0.0%) f8 ...et/collects/racket/match/compiler.rkt:507:40 map [58] 100.0% --------------------------------------------------------------------------- hash-ref! [34] 100.0% [43] 346(0.3%) 0(0.0%) parse-loop14 ...s/racket/match/compiler.rkt:418:15 loop [47] 100.0% --------------------------------------------------------------------------- for-loop [44] 50.0% one-iter [30] 50.0% [44] 334(0.3%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:83:12 for-loop [44] 50.0% match-e [52] 50.0% --------------------------------------------------------------------------- ??? [33] 100.0% [45] 334(0.3%) 0(0.0%) make-alt-table ...elop/src/core/alt-table.rkt:32:0 eval-prog [25] 100.0% --------------------------------------------------------------------------- ??? [33] 100.0% [46] 320(0.3%) 0(0.0%) ??? .../herbie/develop/src/core/alt-table.rkt:54:0 ??? [64] 100.0% --------------------------------------------------------------------------- parse-loop14 [43] 0.0% loop [35] 0.4% map-enodes [38] 0.5% for-loop [14] 0.5% make-exacts-walkup [36] 0.7% loop [47] 97.8% [47] 118312(95.8%) 406(0.3%) loop ...cket/collects/racket/private/map.rkt:40:19 loop [47] 97.8% composed [2] 1.1% set-precompute! [4] 0.5% ??? [64] 0.3% ??? [8] 0.3% hash-ref! [34] 0.0% map [58] 0.0% --------------------------------------------------------------------------- bf-complex-add [31] 11.9% bf-complex-mult [28] 34.1% bf* [37] 54.0% [48] 73734(59.7%) 5294(4.3%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [53] 84.3% bfneg [55] 8.5% --------------------------------------------------------------------------- ...higher-order.rkt:361:33 [39] 100.0% [49] 1978(1.6%) 1654(1.3%) ??? ...cts/racket/contract/private/list.rkt:209:10 ??? [60] 16.4% --------------------------------------------------------------------------- compile16 [41] 100.0% [50] 1686(1.4%) 0(0.0%) temp74_0 (unknown source) expand-single [56] 80.7% compile-top9 [59] 19.3% --------------------------------------------------------------------------- for-loop [40] 100.0% [51] 1270(1.0%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:106:4 merge-egraph-nodes! [54] 100.0% --------------------------------------------------------------------------- for-loop [44] 49.9% for-loop [40] 50.1% [52] 670(0.5%) 0(0.0%) match-e ...herbie/develop/src/core/ematch.rkt:39:0 for-loop [57] 100.0% --------------------------------------------------------------------------- ??? [48] 100.0% [53] 62192(50.4%) 790(0.6%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [61] 72.6% ??? [63] 26.1% --------------------------------------------------------------------------- for-loop [66] 3.3% for-loop [51] 8.4% reduce-to-new! [18] 88.3% [54] 15030(12.2%) 0(0.0%) merge-egraph-nodes! ...p/src/core/egraph.rkt:153:0 update-leader! [62] 94.7% for-loop [66] 3.3% --------------------------------------------------------------------------- ??? [48] 100.0% [55] 6248(5.1%) 352(0.3%) bfneg ...-lib/math/private/bigfloat/mpfr.rkt:618:4 ??? [63] 54.4% new-mpfr [61] 39.9% --------------------------------------------------------------------------- temp74_0 [50] 100.0% [56] 1360(1.1%) 0(0.0%) expand-single (unknown source) expand-capturing-lifts [65] 100.0% --------------------------------------------------------------------------- match-e [52] 100.0% [57] 670(0.5%) 336(0.3%) for-loop ...rbie/develop/src/core/ematch.rkt:52:11 curry* [67] 49.9% --------------------------------------------------------------------------- loop [47] 50.0% f8 [42] 50.0% [58] 346(0.3%) 0(0.0%) map ...acket/collects/racket/private/map.rkt:35:13 hash-ref! [34] 50.0% ??? [64] 50.0% --------------------------------------------------------------------------- temp74_0 [50] 100.0% [59] 326(0.3%) 0(0.0%) compile-top9 (unknown source) compile-forms31 [68] 100.0% --------------------------------------------------------------------------- ??? [49] 100.0% [60] 324(0.3%) 324(0.3%) ??? ...ects/racket/contract/private/guts.rkt:746:8 --------------------------------------------------------------------------- bf [24] 2.2% bfneg [55] 5.1% bfmul [53] 92.6% [61] 48744(39.5%) 48744(39.5%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 --------------------------------------------------------------------------- for-loop [40] 1.2% for-loop [10] 43.7% merge-egraph-nodes! [54] 55.2% [62] 26708(21.6%) 0(0.0%) update-leader! ...evelop/src/core/egraph.rkt:215:0 for-loop [69] 98.8% --------------------------------------------------------------------------- bf [24] 3.2% bfneg [55] 16.8% bfmul [53] 80.1% [63] 20292(16.4%) 20292(16.4%) ??? /home/p92/racket/collects/ffi/unsafe.rkt:898:4 --------------------------------------------------------------------------- ??? [46] 4.8% map [58] 10.4% loop [47] 30.0% ??? [16] 54.8% [64] 3324(2.7%) 1356(1.1%) ??? ...tract/private/arrow-higher-order.rkt:357:33 ->flonum [70] 54.4% best-alt [74] 4.8% --------------------------------------------------------------------------- expand-single [56] 100.0% [65] 1360(1.1%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [71] 73.7% dispatch-transformer41 [72] 26.3% --------------------------------------------------------------------------- merge-egraph-nodes! [54] 100.0% [66] 988(0.8%) 0(0.0%) for-loop ...bie/develop/src/core/egraph.rkt:201:10 merge-egraph-nodes! [54] 100.0% --------------------------------------------------------------------------- for-loop [57] 100.0% [67] 334(0.3%) 0(0.0%) curry* ...racket/collects/racket/function.rkt:44:2 procedure-arity [73] 100.0% --------------------------------------------------------------------------- compile-top9 [59] 100.0% [68] 326(0.3%) 0(0.0%) compile-forms31 (unknown source) for-loop [82] 100.0% --------------------------------------------------------------------------- update-leader! [62] 100.0% [69] 26386(21.4%) 664(0.5%) for-loop ...rbie/develop/src/core/egraph.rkt:220:6 for-loop [75] 97.5% --------------------------------------------------------------------------- ??? [64] 100.0% [70] 1968(1.6%) 990(0.8%) ->flonum ...s/herbie/develop/src/programs.rkt:36:0 return/no-unsupplied [78] 31.9% ??? [79] 17.8% --------------------------------------------------------------------------- expand-capturing-lifts [65] 100.0% [71] 1002(0.8%) 0(0.0%) lambda-clause-expander (unknown source) finish-bodys [77] 66.7% loop [92] 33.3% --------------------------------------------------------------------------- loop [92] 48.3% expand-capturing-lifts [65] 51.7% [72] 692(0.6%) 0(0.0%) dispatch-transformer41 (unknown source) apply-transformer52 [76] 100.0% --------------------------------------------------------------------------- curry* [67] 100.0% [73] 334(0.3%) 334(0.3%) procedure-arity ...ket/private/norm-arity.rkt:7:27 --------------------------------------------------------------------------- ??? [64] 100.0% [74] 320(0.3%) 0(0.0%) best-alt .../herbie/develop/src/mainloop.rkt:110:0 argmins [80] 100.0% --------------------------------------------------------------------------- for-loop [69] 100.0% [75] 25722(20.8%) 0(0.0%) for-loop ...rbie/develop/src/core/egraph.rkt:221:8 hash-update! [81] 100.0% --------------------------------------------------------------------------- dispatch-transformer41 [72] 100.0% [76] 692(0.6%) 0(0.0%) apply-transformer52 (unknown source) apply-transformer-in-context [83]100.0% --------------------------------------------------------------------------- lambda-clause-expander [71] 11.7% for-loop [82] 88.3% [77] 668(0.5%) 0(0.0%) finish-bodys (unknown source) for-loop [82] 100.0% --------------------------------------------------------------------------- ->flonum [70] 100.0% [78] 628(0.5%) 628(0.5%) return/no-unsupplied ...ct/private/arr-i.rkt:534:0 --------------------------------------------------------------------------- ->flonum [70] 100.0% [79] 350(0.3%) 350(0.3%) ??? ...s/racket/contract/private/arr-i.rkt:1252:29 --------------------------------------------------------------------------- best-alt [74] 100.0% [80] 320(0.3%) 0(0.0%) argmins ...ies/herbie/develop/src/common.rkt:129:0 ??? [84] 100.0% --------------------------------------------------------------------------- reduce-to-new! [18] 1.4% for-loop [75] 98.6% [81] 26082(21.1%) 706(0.6%) hash-update! ...cket/private/more-scheme.rkt:365:4 ??? [85] 95.9% ??? [87] 1.4% --------------------------------------------------------------------------- for-loop [82] 6.7% compile-forms31 [68] 32.8% finish-bodys [77] 60.5% [82] 994(0.8%) 326(0.3%) for-loop (unknown source) finish-bodys [77] 53.8% for-loop [82] 6.7% syntax-e [5] 6.7% --------------------------------------------------------------------------- apply-transformer52 [76] 100.0% [83] 692(0.6%) 0(0.0%) apply-transformer-in-context (unknown source) parse-lambda [88] 51.7% datum->syntax6 [89] 48.3% --------------------------------------------------------------------------- argmins [80] 100.0% [84] 320(0.3%) 0(0.0%) ??? ...lies/herbie/develop/src/mainloop.rkt:112:19 eval-errors [86] 100.0% --------------------------------------------------------------------------- hash-update! [81] 100.0% [85] 25016(20.3%) 0(0.0%) ??? ...s/herbie/develop/src/core/egraph.rkt:223:24 for-loop [90] 100.0% --------------------------------------------------------------------------- ??? [84] 14.2% ??? [15] 85.8% [86] 2250(1.8%) 0(0.0%) eval-errors ...herbie/develop/src/points.rkt:292:0 for-loop [91] 100.0% --------------------------------------------------------------------------- hash-update! [81] 100.0% [87] 360(0.3%) 0(0.0%) ??? ...s/herbie/develop/src/core/egraph.rkt:346:16 for-loop [93] 100.0% --------------------------------------------------------------------------- apply-transformer-in-context [83]100.0% [88] 358(0.3%) 0(0.0%) parse-lambda ...llects/racket/private/kw.rkt:585:2 t-subst [94] 100.0% --------------------------------------------------------------------------- apply-transformer-in-context [83]100.0% [89] 334(0.3%) 0(0.0%) datum->syntax6 (unknown source) loop [92] 100.0% --------------------------------------------------------------------------- ??? [85] 100.0% [90] 25016(20.3%) 21774(17.6%) for-loop ...bie/develop/src/core/egraph.rkt:224:26 update-en-expr [95] 13.0% --------------------------------------------------------------------------- eval-errors [86] 100.0% [91] 2250(1.8%) 342(0.3%) for-loop ...es/herbie/develop/src/points.rkt:294:2 ??? [8] 70.0% ??? [97] 14.8% --------------------------------------------------------------------------- datum->syntax6 [89] 8.3% lambda-clause-expander [71] 8.3% loop [92] 16.7% syntax-e/no-taint [12] 66.7% [92] 1002(0.8%) 334(0.3%) loop (unknown source) gf [96] 66.7% loop [92] 16.7% dispatch-transformer41 [72] 8.3% --------------------------------------------------------------------------- ??? [87] 100.0% [93] 360(0.3%) 360(0.3%) for-loop ...bie/develop/src/core/egraph.rkt:347:18 --------------------------------------------------------------------------- parse-lambda [88] 100.0% [94] 358(0.3%) 358(0.3%) t-subst ...lects/racket/private/template.rkt:680:0 --------------------------------------------------------------------------- for-loop [90] 100.0% [95] 3242(2.6%) 1286(1.0%) update-en-expr ...evelop/src/core/egraph.rkt:209:0 for-loop [98] 60.3% --------------------------------------------------------------------------- loop [92] 100.0% [96] 668(0.5%) 0(0.0%) gf (unknown source) propagation-apply [99] 50.0% propagation-apply-shifted [100] 50.0% --------------------------------------------------------------------------- for-loop [91] 100.0% [97] 334(0.3%) 334(0.3%) ??? ...ket/collects/racket/private/for.rkt:1208:14 --------------------------------------------------------------------------- update-en-expr [95] 100.0% [98] 1956(1.6%) 1956(1.6%) for-loop ...rbie/develop/src/core/egraph.rkt:211:6 --------------------------------------------------------------------------- gf [96] 100.0% [99] 334(0.3%) 334(0.3%) propagation-apply (unknown source) --------------------------------------------------------------------------- gf [96] 100.0% [100] 334(0.3%) 334(0.3%) propagation-apply-shifted (unknown source) ---------------------------------------------------------------------------