Profiling results ----------------- Total cpu time observed: 126678ms (out of 130104ms) Number of samples taken: 310 (once every 409ms) (Hiding functions with self<1.0% and local<2.0%: 3 of 98 hidden) ======================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ======================================================================== ??? [14] 100.0% [1] 126678(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [7] 100.0% ------------------------------------------------------------------------ ??? [14] 100.0% [2] 33584(26.5%) 0(0.0%) run-improve39 ...ie/develop/src/mainloop.rkt:287:0 for-loop [8] 88.6% setup-prog!32 [10] 10.4% for-loop [13] 1.0% ------------------------------------------------------------------------ map-enodes [25] 1.1% loop [35] 98.9% [3] 27814(22.0%) 0(0.0%) set-precompute! ...lop/src/core/simplify.rkt:140:0 for-loop [9] 100.0% ------------------------------------------------------------------------ hash-ref! [42] 100.0% [4] 4226(3.3%) 0(0.0%) ??? ...tlies/herbie/develop/src/programs.rkt:153:5 loop [35] 100.0% ------------------------------------------------------------------------ for-loop [88] 100.0% [5] 708(0.6%) 0(0.0%) syntax-e (unknown source) syntax-e/no-taint [11] 100.0% ------------------------------------------------------------------------ hash-ref! [42] 100.0% [6] 322(0.3%) 0(0.0%) f8 ...et/collects/racket/match/compiler.rkt:507:40 map [12] 100.0% ------------------------------------------------------------------------ profile-thunk16 [1] 100.0% [7] 126678(100.0%) 0(0.0%) run ...racket/share/pkgs/profile-lib/main.rkt:39:2 ??? [14] 100.0% ------------------------------------------------------------------------ run-improve39 [2] 100.0% [8] 29770(23.5%) 0(0.0%) for-loop ...herbie/develop/src/mainloop.rkt:298:19 ??? [15] 100.0% ------------------------------------------------------------------------ set-precompute! [3] 100.0% [9] 27814(22.0%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:142:2 reduce-to-new! [16] 51.6% update-leader! [46] 46.0% eval-const-expr [17] 2.4% ------------------------------------------------------------------------ run-improve39 [2] 100.0% [10] 3492(2.8%) 0(0.0%) setup-prog!32 ...bie/develop/src/mainloop.rkt:63:0 loop [19] 100.0% ------------------------------------------------------------------------ syntax-e [5] 100.0% [11] 708(0.6%) 0(0.0%) syntax-e/no-taint (unknown source) loop [90] 100.0% ------------------------------------------------------------------------ loop [35] 50.0% f8 [6] 50.0% [12] 322(0.3%) 0(0.0%) map ...acket/collects/racket/private/map.rkt:35:13 hash-ref! [42] 50.0% ??? [57] 50.0% ------------------------------------------------------------------------ run-improve39 [2] 100.0% [13] 322(0.3%) 0(0.0%) for-loop ...herbie/develop/src/mainloop.rkt:304:10 run-iter! [18] 100.0% ------------------------------------------------------------------------ run [7] 50.0% [14] 126678(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 50.0% loop [19] 36.4% run-improve39 [2] 13.3% eval-errors [21] 0.3% errors-score [22] 0.1% ------------------------------------------------------------------------ for-loop [8] 100.0% [15] 29770(23.5%) 0(0.0%) ??? ...s/herbie/develop/src/core/simplify.rkt:31:0 iterate-egraph!7 [20] 100.0% ------------------------------------------------------------------------ for-loop [9] 100.0% [16] 14340(11.3%) 0(0.0%) reduce-to-new! ...evelop/src/core/egraph.rkt:337:0 merge-egraph-nodes! [41] 97.8% hash-update! [68] 2.2% ------------------------------------------------------------------------ for-loop [9] 100.0% [17] 668(0.5%) 0(0.0%) eval-const-expr .../develop/src/programs.rkt:132:0 eval-prog [36] 100.0% ------------------------------------------------------------------------ for-loop [13] 100.0% [18] 322(0.3%) 0(0.0%) run-iter! ...herbie/develop/src/mainloop.rkt:268:0 localize-error [23] 100.0% ------------------------------------------------------------------------ setup-prog!32 [10] 3.7% ??? [14] 96.3% [19] 95626(75.5%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:167:2 loop [30] 63.7% loop [24] 34.1% make-exacts [27] 1.1% make-exacts* [31] 0.8% ------------------------------------------------------------------------ ??? [15] 100.0% [20] 29770(23.5%) 0(0.0%) iterate-egraph!7 ...lop/src/core/simplify.rkt:55:0 map-enodes [25] 93.4% one-iter [26] 6.6% ------------------------------------------------------------------------ ??? [14] 100.0% [21] 636(0.5%) 0(0.0%) eval-errors ...herbie/develop/src/points.rkt:208:0 for-loop [28] 100.0% ------------------------------------------------------------------------ ??? [14] 100.0% [22] 324(0.3%) 0(0.0%) errors-score ...erbie/develop/src/points.rkt:238:0 loop [29] 100.0% ------------------------------------------------------------------------ run-iter! [18] 100.0% [23] 322(0.3%) 0(0.0%) localize-error ...velop/src/core/localize.rkt:51:0 hash-ref! [42] 100.0% ------------------------------------------------------------------------ loop [19] 100.0% [24] 32570(25.7%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:125:2 make-exacts* [31] 53.2% loop [30] 42.5% eval-prog [36] 4.3% ------------------------------------------------------------------------ iterate-egraph!7 [20] 100.0% [25] 27814(22.0%) 0(0.0%) map-enodes ...ie/develop/src/core/egraph.rkt:140:0 loop [35] 98.9% set-precompute! [3] 1.1% ------------------------------------------------------------------------ iterate-egraph!7 [20] 100.0% [26] 1956(1.5%) 0(0.0%) one-iter ...bie/develop/src/core/simplify.rkt:66:0 for-loop [32] 60.7% for-loop [33] 39.3% ------------------------------------------------------------------------ loop [19] 100.0% [27] 1068(0.8%) 0(0.0%) make-exacts ...herbie/develop/src/points.rkt:123:0 make-exacts* [31] 100.0% ------------------------------------------------------------------------ eval-errors [21] 100.0% [28] 636(0.5%) 310(0.2%) for-loop ...es/herbie/develop/src/points.rkt:210:2 ??? [34] 51.3% ------------------------------------------------------------------------ errors-score [22] 100.0% [29] 324(0.3%) 324(0.3%) loop .../p92/racket/collects/racket/list.rkt:559:2 ------------------------------------------------------------------------ loop [24] 18.5% loop [19] 81.5% [30] 74708(59.0%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:108:4 loop [35] 99.0% ??? [40] 1.0% ------------------------------------------------------------------------ loop [19] 4.2% make-exacts [27] 5.6% loop [24] 90.2% [31] 19190(15.1%) 0(0.0%) make-exacts* ...erbie/develop/src/points.rkt:105:0 loop [35] 78.6% eval-prog [36] 21.4% ------------------------------------------------------------------------ one-iter [26] 100.0% [32] 1188(0.9%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:113:2 for-loop [37] 74.7% set [39] 25.3% ------------------------------------------------------------------------ for-loop [33] 50.0% one-iter [26] 50.0% [33] 768(0.6%) 452(0.4%) for-loop ...ie/develop/src/core/simplify.rkt:76:12 for-loop [33] 50.0% match-e [38] 20.6% ------------------------------------------------------------------------ for-loop [28] 100.0% [34] 326(0.3%) 326(0.3%) ??? ...ket/collects/racket/private/for.rkt:1208:14 ------------------------------------------------------------------------ parse-loop14 [49] 0.0% make-exacts* [31] 0.1% map-enodes [25] 0.4% loop [30] 0.6% ??? [4] 3.5% loop [35] 95.4% [35] 121072(95.6%) 464(0.4%) loop ...cket/collects/racket/private/map.rkt:40:19 loop [35] 95.4% hash-ref! [42] 3.5% ??? [40] 0.7% set-precompute! [3] 0.4% map [12] 0.0% ??? [57] 0.0% ------------------------------------------------------------------------ eval-const-expr [17] 10.8% loop [24] 22.9% make-exacts* [31] 66.4% [36] 6196(4.9%) 0(0.0%) eval-prog ...herbie/develop/src/programs.rkt:109:0 hash-ref! [42] 68.2% temp68_2 [43] 21.5% loop [90] 10.3% ------------------------------------------------------------------------ for-loop [32] 100.0% [37] 888(0.7%) 0(0.0%) for-loop ...bie/develop/src/core/simplify.rkt:98:4 merge-egraph-nodes! [41] 100.0% ------------------------------------------------------------------------ for-loop [50] 50.0% for-loop [33] 50.0% [38] 316(0.2%) 0(0.0%) match-e ...herbie/develop/src/core/ematch.rkt:46:0 for-loop [44] 100.0% ------------------------------------------------------------------------ for-loop [32] 100.0% [39] 300(0.2%) 300(0.2%) set ...collects/racket/private/set-types.rkt:981:0 ------------------------------------------------------------------------ loop [30] 0.9% loop [35] 99.1% [40] 88874(70.2%) 0(0.0%) ??? ...tlies/herbie/develop/src/programs.rkt:129:2 ??? [45] 100.0% ------------------------------------------------------------------------ for-loop [37] 6.0% reduce-to-new! [16] 92.9% [41] 14916(11.8%) 0(0.0%) merge-egraph-nodes! ...p/src/core/egraph.rkt:153:0 update-leader! [46] 96.9% for-loop [51] 2.0% ------------------------------------------------------------------------ map [12] 2.4% localize-error [23] 2.4% eval-prog [36] 16.8% loop [35] 78.5% [42] 4548(3.6%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 ??? [4] 76.2% bigfloat-hash [47] 16.8% parse-loop14 [49] 4.7% f8 [6] 2.4% ------------------------------------------------------------------------ eval-prog [36] 100.0% [43] 1332(1.1%) 0(0.0%) temp68_2 (unknown source) compile16 [48] 100.0% ------------------------------------------------------------------------ match-e [38] 100.0% [44] 316(0.2%) 316(0.2%) for-loop ...rbie/develop/src/core/ematch.rkt:59:11 for-loop [50] 50.0% ------------------------------------------------------------------------ ??? [40] 100.0% [45] 88874(70.2%) 384(0.3%) ??? ...acket/collects/racket/private/kw.rkt:592:14 ??? [52] 99.2% ??? [57] 0.4% ------------------------------------------------------------------------ for-loop [9] 46.7% merge-egraph-nodes! [41] 53.3% [46] 27424(21.6%) 0(0.0%) update-leader! ...evelop/src/core/egraph.rkt:218:0 for-loop [53] 96.1% custom-set-union! [58] 3.9% ------------------------------------------------------------------------ hash-ref! [42] 100.0% [47] 4226(3.3%) 0(0.0%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:167:0 sig+exp->bigfloat [54] 50.1% bfcanonicalize [55] 41.5% bigfloat->sig+exp [59] 8.4% ------------------------------------------------------------------------ temp68_2 [43] 100.0% [48] 1332(1.1%) 0(0.0%) compile16 (unknown source) temp74_0 [56] 100.0% ------------------------------------------------------------------------ hash-ref! [42] 100.0% [49] 322(0.3%) 0(0.0%) parse-loop14 ...s/racket/match/compiler.rkt:418:15 loop [35] 100.0% ------------------------------------------------------------------------ for-loop [44] 100.0% [50] 316(0.2%) 0(0.0%) for-loop ...rbie/develop/src/core/ematch.rkt:65:37 match-e [38] 100.0% ------------------------------------------------------------------------ merge-egraph-nodes! [41] 100.0% [51] 298(0.2%) 0(0.0%) for-loop ...ies/herbie/develop/src/common.rkt:79:9 update-en-expr [87] 100.0% ------------------------------------------------------------------------ ??? [45] 100.0% [52] 88168(69.6%) 1292(1.0%) ??? ...tract/private/arrow-higher-order.rkt:361:33 bf-complex-mult [60] 91.4% bf-complex-add [62] 7.2% ------------------------------------------------------------------------ update-leader! [46] 100.0% [53] 26354(20.8%) 664(0.5%) for-loop ...rbie/develop/src/core/egraph.rkt:223:6 for-loop [61] 97.5% ------------------------------------------------------------------------ bigfloat-hash [47] 100.0% [54] 2116(1.7%) 0(0.0%) sig+exp->bigfloat ...ivate/bigfloat/mpfr.rkt:387:0 new-mpfr [85] 100.0% ------------------------------------------------------------------------ bigfloat-hash [47] 100.0% [55] 1754(1.4%) 1754(1.4%) bfcanonicalize .../private/bigfloat/mpfr.rkt:157:0 ------------------------------------------------------------------------ compile16 [48] 100.0% [56] 1332(1.1%) 0(0.0%) temp74_0 (unknown source) expand-single [64] 78.1% compile-top9 [66] 21.9% ------------------------------------------------------------------------ map [12] 29.5% ??? [45] 29.5% loop [35] 41.0% [57] 1092(0.9%) 322(0.3%) ??? ...tract/private/arrow-higher-order.rkt:357:33 ->flonum [65] 70.5% ------------------------------------------------------------------------ update-leader! [46] 100.0% [58] 1070(0.8%) 0(0.0%) custom-set-union! ...t/private/set-types.rkt:245:0 for-loop [63] 100.0% ------------------------------------------------------------------------ bigfloat-hash [47] 100.0% [59] 356(0.3%) 356(0.3%) bigfloat->sig+exp ...ivate/bigfloat/mpfr.rkt:372:0 ------------------------------------------------------------------------ ??? [52] 100.0% [60] 80548(63.6%) 5150(4.1%) bf-complex-mult ...develop/src/bigcomplex.rkt:31:0 bf* [67] 54.1% ??? [72] 34.4% ...higher-order.rkt:361:33 [69] 5.1% ------------------------------------------------------------------------ for-loop [53] 100.0% [61] 25690(20.3%) 0(0.0%) for-loop ...rbie/develop/src/core/egraph.rkt:224:8 hash-update! [68] 100.0% ------------------------------------------------------------------------ ??? [52] 100.0% [62] 6328(5.0%) 638(0.5%) bf-complex-add .../develop/src/bigcomplex.rkt:24:0 ??? [72] 89.9% ------------------------------------------------------------------------ custom-set-union! [58] 100.0% [63] 1070(0.8%) 1070(0.8%) for-loop ...cts/racket/private/set-types.rkt:253:4 ------------------------------------------------------------------------ temp74_0 [56] 100.0% [64] 1040(0.8%) 0(0.0%) expand-single (unknown source) expand-capturing-lifts [70] 100.0% ------------------------------------------------------------------------ ??? [57] 100.0% [65] 770(0.6%) 770(0.6%) ->flonum ...s/herbie/develop/src/programs.rkt:36:0 ------------------------------------------------------------------------ temp74_0 [56] 100.0% [66] 292(0.2%) 0(0.0%) compile-top9 (unknown source) compile-forms31 [71] 100.0% ------------------------------------------------------------------------ bf-complex-mult [60] 100.0% [67] 43570(34.4%) 786(0.6%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [72] 98.2% ------------------------------------------------------------------------ reduce-to-new! [16] 1.2% for-loop [61] 98.8% [68] 26002(20.5%) 0(0.0%) hash-update! ...cket/private/more-scheme.rkt:365:4 ??? [73] 98.8% ??? [76] 1.2% ------------------------------------------------------------------------ bf-complex-mult [60] 100.0% [69] 4116(3.2%) 1658(1.3%) ...higher-order.rkt:361:33 (unknown source) ??? [74] 49.5% ??? [81] 10.3% ------------------------------------------------------------------------ loop [90] 38.0% expand-single [64] 62.0% [70] 1678(1.3%) 336(0.3%) expand-capturing-lifts (unknown source) lambda-clause-expander [75] 62.0% dispatch-transformer41 [77] 18.0% ------------------------------------------------------------------------ compile-top9 [66] 100.0% [71] 292(0.2%) 0(0.0%) compile-forms31 (unknown source) for-loop [88] 100.0% ------------------------------------------------------------------------ bf-complex-add [62] 7.5% bf-complex-mult [60] 36.4% bf* [67] 56.2% [72] 76186(60.1%) 5442(4.3%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [78] 83.9% bfneg [80] 8.9% ------------------------------------------------------------------------ hash-update! [68] 100.0% [73] 25690(20.3%) 1790(1.4%) ??? ...s/herbie/develop/src/core/egraph.rkt:226:24 for-loop [79] 91.9% ------------------------------------------------------------------------ ...higher-order.rkt:361:33 [69]100.0% [74] 2036(1.6%) 1262(1.0%) ??? ...cts/racket/contract/private/list.rkt:209:10 ??? [81] 38.0% ------------------------------------------------------------------------ expand-capturing-lifts [70] 100.0% [75] 1040(0.8%) 0(0.0%) lambda-clause-expander (unknown source) finish-bodys [82] 100.0% ------------------------------------------------------------------------ hash-update! [68] 100.0% [76] 312(0.2%) 0(0.0%) ??? ...s/herbie/develop/src/core/egraph.rkt:349:16 for-loop [83] 100.0% ------------------------------------------------------------------------ expand-capturing-lifts [70] 100.0% [77] 302(0.2%) 0(0.0%) dispatch-transformer41 (unknown source) apply-transformer52 [84] 100.0% ------------------------------------------------------------------------ ??? [72] 100.0% [78] 63944(50.5%) 3814(3.0%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [85] 64.9% ??? [86] 29.1% ------------------------------------------------------------------------ ??? [73] 100.0% [79] 23600(18.6%) 19904(15.7%) for-loop ...bie/develop/src/core/egraph.rkt:227:26 update-en-expr [87] 15.7% ------------------------------------------------------------------------ ??? [72] 100.0% [80] 6800(5.4%) 362(0.3%) bfneg ...-lib/math/private/bigfloat/mpfr.rkt:618:4 new-mpfr [85] 77.8% ??? [86] 16.9% ------------------------------------------------------------------------ ...higher-order.rkt:361:33 [69] 35.3% ??? [74] 64.7% [81] 1196(0.9%) 1196(0.9%) ??? ...ects/racket/contract/private/guts.rkt:746:8 ------------------------------------------------------------------------ lambda-clause-expander [75] 13.5% for-loop [88] 86.5% [82] 1040(0.8%) 0(0.0%) finish-bodys (unknown source) for-loop [88] 100.0% ------------------------------------------------------------------------ ??? [76] 100.0% [83] 312(0.2%) 312(0.2%) for-loop ...bie/develop/src/core/egraph.rkt:350:18 ------------------------------------------------------------------------ dispatch-transformer41 [77] 100.0% [84] 302(0.2%) 0(0.0%) apply-transformer52 (unknown source) loop [90] 100.0% ------------------------------------------------------------------------ sig+exp->bigfloat [54] 4.3% bfneg [80] 10.8% bfmul [78] 84.9% [85] 48922(38.6%) 48922(38.6%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ------------------------------------------------------------------------ bfneg [80] 5.8% bfmul [78] 94.2% [86] 19762(15.6%) 19762(15.6%) ??? /home/p92/racket/collects/ffi/unsafe.rkt:898:4 ------------------------------------------------------------------------ for-loop [51] 7.5% for-loop [79] 92.5% [87] 3994(3.2%) 986(0.8%) update-en-expr ...evelop/src/core/egraph.rkt:212:0 for-loop [89] 75.3% ------------------------------------------------------------------------ for-loop [88] 2.2% compile-forms31 [71] 21.9% finish-bodys [82] 75.9% [88] 1332(1.1%) 292(0.2%) for-loop (unknown source) finish-bodys [82] 65.4% loop [90] 8.3% for-loop [88] 2.2% syntax-e [5] 2.2% ------------------------------------------------------------------------ update-en-expr [87] 100.0% [89] 3008(2.4%) 3008(2.4%) for-loop ...rbie/develop/src/core/egraph.rkt:214:6 ------------------------------------------------------------------------ apply-transformer52 [84] 9.0% for-loop [88] 19.8% eval-prog [36] 29.0% syntax-e/no-taint [11] 42.2% [90] 1678(1.3%) 332(0.3%) loop (unknown source) gf [91] 42.2% expand-capturing-lifts [70] 29.0% core-form-sym [92] 9.0% ------------------------------------------------------------------------ loop [90] 100.0% [91] 708(0.6%) 0(0.0%) gf (unknown source) propagation-apply [93] 57.6% cache-or-reuse-set [95] 42.4% ------------------------------------------------------------------------ loop [90] 100.0% [92] 302(0.2%) 0(0.0%) core-form-sym (unknown source) resolve+shift28 [94] 100.0% ------------------------------------------------------------------------ gf [91] 100.0% [93] 408(0.3%) 408(0.3%) propagation-apply (unknown source) ------------------------------------------------------------------------ core-form-sym [92] 100.0% [94] 302(0.2%) 302(0.2%) resolve+shift28 (unknown source) ------------------------------------------------------------------------ gf [91] 100.0% [95] 300(0.2%) 300(0.2%) cache-or-reuse-set (unknown source) ------------------------------------------------------------------------