Profiling results ----------------- Total cpu time observed: 5812ms (out of 6080ms) Number of samples taken: 32 (once every 182ms) (Hiding functions with self<1.0% and local<2.0%: 1 of 103 hidden) ===================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ===================================================================== [1] 5812(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [6] 100.0% --------------------------------------------------------------------- ??? [73] 100.0% [2] 336(5.8%) 0(0.0%) approximate9 ...faster-rr/src/core/taylor.rkt:11:0 loop [7] 100.0% --------------------------------------------------------------------- for-loop [100] 100.0% [3] 164(2.8%) 0(0.0%) syntax-e (unknown source) syntax-e/no-taint [8] 100.0% --------------------------------------------------------------------- hash-ref! [59] 100.0% [4] 152(2.6%) 0(0.0%) ??? ...herbie/faster-rr/src/core/taylor.rkt:300:23 for-loop [9] 100.0% --------------------------------------------------------------------- hash-ref! [59] 100.0% [5] 152(2.6%) 0(0.0%) ??? ...herbie/faster-rr/src/core/taylor.rkt:290:26 for-loop [10] 100.0% --------------------------------------------------------------------- ??? [1] 100.0% [6] 5812(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [11] 100.0% --------------------------------------------------------------------- approximate9 [2] 100.0% [7] 336(5.8%) 0(0.0%) loop .../herbie/faster-rr/src/core/taylor.rkt:86:4 hash-ref! [59] 100.0% --------------------------------------------------------------------- syntax-e [3] 100.0% [8] 164(2.8%) 0(0.0%) syntax-e/no-taint (unknown source) loop [52] 100.0% --------------------------------------------------------------------- ??? [4] 100.0% [9] 152(2.6%) 0(0.0%) for-loop ...e/faster-rr/src/core/taylor.rkt:303:27 hash-ref! [59] 100.0% --------------------------------------------------------------------- ??? [5] 100.0% [10] 152(2.6%) 0(0.0%) for-loop ...e/faster-rr/src/core/taylor.rkt:290:52 hash-ref! [59] 100.0% --------------------------------------------------------------------- profile-thunk16 [6] 100.0% [11] 5812(100.0%) 0(0.0%) run ...et-7.5/share/pkgs/profile-lib/main.rkt:39:2 .../more-scheme.rkt:261:28 [12] 100.0% --------------------------------------------------------------------- for-loop [58] 8.3% run [11] 91.7% [12] 5812(100.0%) 218(3.8%) .../more-scheme.rkt:261:28 (unknown source) run-improve47 [13] 52.9% prepare-points-intervals [14] 29.8% ??? [15] 4.7% get-final-combination [17] 3.3% oracle-error [18] 3.0% errors [51] 2.8% ??? [16] 1.7% --------------------------------------------------------------------- .../more-scheme.rkt:261:28 [12] 100.0% [13] 3558(61.2%) 0(0.0%) run-improve47 .../faster-rr/src/mainloop.rkt:346:0 for-loop [19] 95.7% simplify! [28] 4.3% --------------------------------------------------------------------- .../more-scheme.rkt:261:28 [12] 100.0% [14] 1730(29.8%) 0(0.0%) prepare-points-intervals ...r/src/points.rkt:120:0 loop [20] 100.0% --------------------------------------------------------------------- .../more-scheme.rkt:261:28 [12] 100.0% [15] 552(9.5%) 552(9.5%) ??? ...rbie/faster-rr/src/syntax/syntax.rkt:214:17 --------------------------------------------------------------------- .../more-scheme.rkt:261:28 [12] 100.0% [16] 200(3.4%) 200(3.4%) ??? ...cket-7.5/collects/racket/function.rkt:93:12 --------------------------------------------------------------------- .../more-scheme.rkt:261:28 [12] 100.0% [17] 192(3.3%) 0(0.0%) get-final-combination ...rr/src/mainloop.rkt:371:0 infer-splitpoints [21] 100.0% --------------------------------------------------------------------- .../more-scheme.rkt:261:28 [12] 100.0% [18] 172(3.0%) 0(0.0%) oracle-error ...bie/faster-rr/src/points.rkt:177:0 for-loop [22] 100.0% --------------------------------------------------------------------- run-improve47 [13] 100.0% [19] 3406(58.6%) 0(0.0%) for-loop ...erbie/faster-rr/src/mainloop.rkt:365:4 run-iter! [23] 100.0% --------------------------------------------------------------------- prepare-points-intervals [14] 100.0% [20] 1730(29.8%) 0(0.0%) loop ...lies/herbie/faster-rr/src/points.rkt:132:4 loop [24] 70.9% ??? [25] 29.1% --------------------------------------------------------------------- get-final-combination [17] 100.0% [21] 192(3.3%) 0(0.0%) infer-splitpoints ...-rr/src/core/regimes.rkt:33:0 for-loop [26] 100.0% --------------------------------------------------------------------- oracle-error [18] 100.0% [22] 172(3.0%) 0(0.0%) for-loop .../herbie/faster-rr/src/points.rkt:178:2 loop [52] 100.0% --------------------------------------------------------------------- for-loop [19] 100.0% [23] 3406(58.6%) 0(0.0%) run-iter! ...rbie/faster-rr/src/mainloop.rkt:327:0 finalize-iter! [27] 56.4% simplify! [28] 16.4% gen-series! [29] 16.1% gen-rewrites! [30] 11.1% --------------------------------------------------------------------- loop [20] 100.0% [24] 1226(21.1%) 374(6.4%) loop ...tlies/herbie/faster-rr/src/points.rkt:79:2 ??? [73] 56.1% ??? [33] 13.4% --------------------------------------------------------------------- loop [20] 100.0% [25] 504(8.7%) 330(5.7%) ??? ...tlies/herbie/faster-rr/src/points.rkt:113:6 sample-multi-bounded [32] 34.5% --------------------------------------------------------------------- infer-splitpoints [21] 100.0% [26] 192(3.3%) 0(0.0%) for-loop ...ie/faster-rr/src/core/regimes.rkt:45:6 option-on-expr [31] 100.0% --------------------------------------------------------------------- run-iter! [23] 100.0% [27] 1920(33.0%) 0(0.0%) finalize-iter! ...faster-rr/src/mainloop.rkt:278:0 ??? [34] 100.0% --------------------------------------------------------------------- run-improve47 [13] 21.4% run-iter! [23] 78.6% [28] 710(12.2%) 0(0.0%) simplify! ...rbie/faster-rr/src/mainloop.rkt:224:0 ??? [35] 100.0% --------------------------------------------------------------------- run-iter! [23] 100.0% [29] 550(9.5%) 0(0.0%) gen-series! ...ie/faster-rr/src/mainloop.rkt:160:0 for-loop [36] 100.0% --------------------------------------------------------------------- run-iter! [23] 100.0% [30] 378(6.5%) 0(0.0%) gen-rewrites! .../faster-rr/src/mainloop.rkt:183:0 for-loop [37] 100.0% --------------------------------------------------------------------- for-loop [26] 100.0% [31] 192(3.3%) 0(0.0%) option-on-expr ...er-rr/src/core/regimes.rkt:124:0 err-lsts->split-indices [38] 100.0% --------------------------------------------------------------------- ??? [25] 100.0% [32] 174(3.0%) 0(0.0%) sample-multi-bounded ...ter-rr/src/points.rkt:14:0 random-ranges [39] 100.0% --------------------------------------------------------------------- loop [24] 100.0% [33] 164(2.8%) 164(2.8%) ??? ...tract/private/arrow-higher-order.rkt:379:33 --------------------------------------------------------------------- finalize-iter! [27] 100.0% [34] 1920(33.0%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:486:18 for-loop [40] 100.0% --------------------------------------------------------------------- simplify! [28] 100.0% [35] 710(12.2%) 0(0.0%) ??? ...herbie/faster-rr/src/core/simplify.rkt:42:0 egraph-run [41] 100.0% --------------------------------------------------------------------- gen-series! [29] 100.0% [36] 550(9.5%) 0(0.0%) for-loop ...erbie/faster-rr/src/mainloop.rkt:167:7 taylor-alt [42] 100.0% --------------------------------------------------------------------- gen-rewrites! [30] 100.0% [37] 378(6.5%) 0(0.0%) for-loop ...rbie/faster-rr/src/mainloop.rkt:191:11 rewrite-expression-head24 [43] 100.0% --------------------------------------------------------------------- option-on-expr [31] 100.0% [38] 192(3.3%) 0(0.0%) err-lsts->split-indices .../core/regimes.rkt:263:0 loop [44] 100.0% --------------------------------------------------------------------- sample-multi-bounded [32] 100.0% [39] 174(3.0%) 0(0.0%) random-ranges ...ie/faster-rr/src/common.rkt:164:0 build--> [45] 100.0% --------------------------------------------------------------------- ??? [34] 100.0% [40] 1920(33.0%) 0(0.0%) for-loop .../faster-rr/src/core/alt-table.rkt:46:2 atab-add-altn [46] 60.4% errors [51] 39.6% --------------------------------------------------------------------- ??? [35] 100.0% [41] 710(12.2%) 0(0.0%) egraph-run .../pkgs/egg-herbie-linux/main.rkt:49:0 egraph-add-exprs [47] 100.0% --------------------------------------------------------------------- for-loop [36] 100.0% [42] 550(9.5%) 0(0.0%) taylor-alt ...bie/faster-rr/src/mainloop.rkt:146:0 for-loop [48] 100.0% --------------------------------------------------------------------- for-loop [37] 100.0% [43] 378(6.5%) 0(0.0%) rewrite-expression-head24 ...core/matcher.rkt:85:0 rewriter [49] 100.0% --------------------------------------------------------------------- err-lsts->split-indices [38] 100.0% [44] 192(3.3%) 0(0.0%) loop ...erbie/faster-rr/src/core/regimes.rkt:314:4 for-loop [50] 100.0% --------------------------------------------------------------------- random-ranges [39] 100.0% [45] 174(3.0%) 174(3.0%) build--> ...act/private/arrow-val-first.rkt:1214:0 --------------------------------------------------------------------- for-loop [40] 100.0% [46] 1160(20.0%) 0(0.0%) atab-add-altn ...r-rr/src/core/alt-table.rkt:194:0 errors [51] 67.6% override-at-pnts [55] 18.4% append-at-pnts [57] 14.0% --------------------------------------------------------------------- egraph-run [41] 100.0% [47] 710(12.2%) 0(0.0%) egraph-add-exprs ...gg-herbie-linux/main.rkt:111:0 ??? [54] 51.0% loop [52] 49.0% --------------------------------------------------------------------- taylor-alt [42] 100.0% [48] 550(9.5%) 0(0.0%) for-loop ...erbie/faster-rr/src/mainloop.rkt:152:6 ??? [73] 100.0% --------------------------------------------------------------------- matcher [69] 39.7% rewrite-expression-head24 [43] 60.3% [49] 378(6.5%) 0(0.0%) rewriter ...ie/faster-rr/src/core/matcher.rkt:87:2 for-loop [53] 100.0% --------------------------------------------------------------------- loop [44] 100.0% [50] 192(3.3%) 0(0.0%) for-loop ...e/faster-rr/src/core/regimes.rkt:279:4 for-loop [56] 100.0% --------------------------------------------------------------------- .../more-scheme.rkt:261:28 [12] 9.4% for-loop [40] 44.6% atab-add-altn [46] 46.0% [51] 1704(29.3%) 0(0.0%) errors ...es/herbie/faster-rr/src/points.rkt:190:0 for-loop [58] 78.6% temp85_0 [60] 21.4% --------------------------------------------------------------------- expr->egg-expr-helper [63] 0.5% egraph-add-exprs [47] 1.1% syntax-e/no-taint [8] 24.0% for-loop [22] 25.1% loop [52] 48.2% [52] 684(11.8%) 162(2.8%) loop (unknown source) loop [52] 48.2% ??? [64] 25.1% gf [65] 24.0% expr->egg-expr-helper [63] 1.0% --------------------------------------------------------------------- rewriter [49] 100.0% [53] 378(6.5%) 0(0.0%) for-loop ...ie/faster-rr/src/core/matcher.rkt:89:4 hash-ref! [59] 100.0% --------------------------------------------------------------------- egraph-add-exprs [47] 100.0% [54] 362(6.2%) 0(0.0%) ??? ...erbie/faster-rr/src/core/simplify.rkt:102:6 for-loop [61] 100.0% --------------------------------------------------------------------- atab-add-altn [46] 100.0% [55] 214(3.7%) 0(0.0%) override-at-pnts ...r/src/core/alt-table.rkt:129:0 for-loop [62] 100.0% --------------------------------------------------------------------- for-loop [50] 100.0% [56] 192(3.3%) 192(3.3%) for-loop ...e/faster-rr/src/core/regimes.rkt:283:8 --------------------------------------------------------------------- atab-add-altn [46] 100.0% [57] 162(2.8%) 0(0.0%) append-at-pnts ...-rr/src/core/alt-table.rkt:137:0 loop [66] 100.0% --------------------------------------------------------------------- errors [51] 100.0% [58] 1340(23.1%) 370(6.4%) for-loop .../herbie/faster-rr/src/points.rkt:192:2 .../more-scheme.rkt:261:28 [12] 72.4% --------------------------------------------------------------------- for-loop [10] 3.0% ??? [71] 3.0% for-loop [9] 6.1% ??? [70] 11.6% ??? [73] 11.6% loop [7] 11.6% for-loop [81] 12.0% for-loop [53] 40.9% [59] 714(12.3%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 matcher [69] 52.9% ??? [71] 14.7% ??? [70] 11.6% f40 [67] 11.6% ??? [4] 6.1% ??? [5] 3.0% --------------------------------------------------------------------- eval-const-expr [88] 33.6% errors [51] 66.4% [60] 548(9.4%) 0(0.0%) temp85_0 (unknown source) compile15 [68] 100.0% --------------------------------------------------------------------- ??? [54] 100.0% [61] 362(6.2%) 362(6.2%) for-loop .../faster-rr/src/core/simplify.rkt:121:2 --------------------------------------------------------------------- override-at-pnts [55] 100.0% [62] 214(3.7%) 214(3.7%) for-loop ...aster-rr/src/core/alt-table.rkt:130:22 --------------------------------------------------------------------- loop [52] 100.0% [63] 186(3.2%) 186(3.2%) expr->egg-expr-helper ...erbie-linux/main.rkt:77:0 loop [52] 50.0% --------------------------------------------------------------------- loop [52] 100.0% [64] 172(3.0%) 172(3.0%) ??? ...lies/herbie/faster-rr/src/points.rkt:179:26 --------------------------------------------------------------------- loop [52] 100.0% [65] 164(2.8%) 0(0.0%) gf (unknown source) propagation-merge [72] 100.0% --------------------------------------------------------------------- append-at-pnts [57] 100.0% [66] 162(2.8%) 162(2.8%) loop ...rbie/faster-rr/src/core/alt-table.rkt:92:2 --------------------------------------------------------------------- ??? [73] 13.0% map [74] 25.9% hash-ref! [59] 61.1% [67] 550(9.5%) 0(0.0%) f40 (unknown source) map [74] 100.0% --------------------------------------------------------------------- temp85_0 [60] 100.0% [68] 548(9.4%) 0(0.0%) compile15 (unknown source) temp91_0 [75] 100.0% --------------------------------------------------------------------- hash-ref! [59] 100.0% [69] 378(6.5%) 0(0.0%) matcher ...ie/faster-rr/src/core/matcher.rkt:119:2 ??? [76] 69.8% rewriter [49] 22.7% constant? [98] 7.6% --------------------------------------------------------------------- hash-ref! [59] 100.0% [70] 336(5.8%) 0(0.0%) ??? .../herbie/faster-rr/src/core/taylor.rkt:44:15 hash-ref! [59] 100.0% --------------------------------------------------------------------- hash-ref! [59] 100.0% [71] 336(5.8%) 0(0.0%) ??? .../herbie/faster-rr/src/core/taylor.rkt:67:15 ??? [73] 77.4% hash-ref! [59] 22.6% --------------------------------------------------------------------- gf [65] 100.0% [72] 164(2.8%) 164(2.8%) propagation-merge (unknown source) --------------------------------------------------------------------- ??? [71] 9.0% location-do [77] 17.7% for-loop [48] 17.7% loop [24] 55.6% [73] 1238(21.3%) 188(3.2%) ??? ...tract/private/arrow-higher-order.rkt:375:33 ival-mult [79] 40.4% location-do [77] 17.7% approximate9 [2] 9.0% hash-ref! [59] 9.0% f40 [67] 8.6% --------------------------------------------------------------------- f40 [67] 100.0% [74] 550(9.5%) 0(0.0%) map ...t-7.5/collects/racket/private/map.rkt:35:13 parse-loop74 [82] 33.5% f61 [84] 27.6% f40 [67] 25.9% make-multiplication-node [80] 13.0% --------------------------------------------------------------------- compile15 [68] 100.0% [75] 548(9.4%) 0(0.0%) temp91_0 (unknown source) temp118_0 [78] 100.0% --------------------------------------------------------------------- matcher [69] 100.0% [76] 378(6.5%) 0(0.0%) ??? ...erbie/faster-rr/src/core/matcher.rkt:131:11 for-loop [81] 52.9% for-loop [83] 47.1% --------------------------------------------------------------------- ??? [73] 43.1% location-do [77] 56.9% [77] 550(9.5%) 0(0.0%) location-do ...bie/faster-rr/src/programs.rkt:63:0 location-do [77] 56.9% ??? [73] 43.1% --------------------------------------------------------------------- temp91_0 [75] 100.0% [78] 548(9.4%) 0(0.0%) temp118_0 (unknown source) expand-capturing-lifts [85] 100.0% --------------------------------------------------------------------- ??? [73] 100.0% [79] 500(8.6%) 0(0.0%) ival-mult ...e/faster-rr/src/biginterval.rkt:120:0 bfmul [86] 100.0% --------------------------------------------------------------------- map [74] 100.0% [80] 214(3.7%) 0(0.0%) make-multiplication-node .../core/reduce.rkt:232:0 make-multiplication-subnode [87]100.0% --------------------------------------------------------------------- ??? [76] 100.0% [81] 200(3.4%) 0(0.0%) for-loop .../faster-rr/src/core/matcher.rkt:133:15 hash-ref! [59] 100.0% --------------------------------------------------------------------- map [74] 100.0% [82] 184(3.2%) 0(0.0%) parse-loop74 (unknown source) eval-const-expr [88] 100.0% --------------------------------------------------------------------- ??? [76] 100.0% [83] 178(3.1%) 0(0.0%) for-loop ...e/faster-rr/src/core/matcher.rkt:103:4 foldl [89] 100.0% --------------------------------------------------------------------- map [74] 100.0% [84] 152(2.6%) 0(0.0%) f61 (unknown source) composed [90] 100.0% --------------------------------------------------------------------- temp118_0 [78] 100.0% [85] 548(9.4%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [91] 100.0% --------------------------------------------------------------------- ival-mult [79] 100.0% [86] 500(8.6%) 0(0.0%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:835:4 ??? [92] 63.6% new-mpfr [94] 36.4% --------------------------------------------------------------------- make-multiplication-node [80] 100.0% [87] 214(3.7%) 0(0.0%) make-multiplication-subnode ...re/reduce.rkt:242:0 compose [93] 100.0% --------------------------------------------------------------------- parse-loop74 [82] 100.0% [88] 184(3.2%) 0(0.0%) eval-const-expr ...aster-rr/src/programs.rkt:120:0 temp85_0 [60] 100.0% --------------------------------------------------------------------- for-loop [83] 100.0% [89] 178(3.1%) 0(0.0%) foldl ....5/collects/racket/private/list.rkt:229:4 ??? [95] 100.0% --------------------------------------------------------------------- f61 [84] 100.0% [90] 152(2.6%) 0(0.0%) composed ...ollects/racket/private/list.rkt:321:16 free-variables [96] 100.0% --------------------------------------------------------------------- expand-capturing-lifts [85] 100.0% [91] 548(9.4%) 0(0.0%) lambda-clause-expander (unknown source) finish-bodys [97] 100.0% --------------------------------------------------------------------- bfmul [86] 100.0% [92] 318(5.5%) 318(5.5%) ??? /opt/racket-7.5/collects/ffi/unsafe.rkt:938:4 --------------------------------------------------------------------- make-multiplication-subnode [87]100.0% [93] 214(3.7%) 214(3.7%) compose ...collects/racket/private/list.rkt:385:12 --------------------------------------------------------------------- bfmul [86] 100.0% [94] 182(3.1%) 182(3.1%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:348:0 --------------------------------------------------------------------- foldl [89] 100.0% [95] 178(3.1%) 178(3.1%) ??? .../herbie/faster-rr/src/core/matcher.rkt:25:7 --------------------------------------------------------------------- composed [90] 33.3% append-map [99] 66.7% [96] 152(2.6%) 0(0.0%) free-variables .../faster-rr/src/programs.rkt:56:0 append-map [99] 66.7% constant? [98] 33.3% --------------------------------------------------------------------- lambda-clause-expander [91] 14.4% for-loop [100] 85.6% [97] 548(9.4%) 0(0.0%) finish-bodys (unknown source) for-loop [100] 100.0% --------------------------------------------------------------------- free-variables [96] 43.2% matcher [69] 56.8% [98] 352(6.1%) 0(0.0%) constant? ...faster-rr/src/syntax/syntax.rkt:726:0 value? [101] 100.0% --------------------------------------------------------------------- free-variables [96] 100.0% [99] 152(2.6%) 0(0.0%) append-map ...t-7.5/collects/racket/list.rkt:586:2 free-variables [96] 100.0% --------------------------------------------------------------------- for-loop [100] 23.6% finish-bodys [97] 76.4% [100] 548(9.4%) 184(3.2%) for-loop (unknown source) finish-bodys [97] 65.9% for-loop [100] 23.6% add-local-binding!39 [102] 2.6% syntax-e [3] 2.3% --------------------------------------------------------------------- constant? [98] 100.0% [101] 352(6.1%) 352(6.1%) value? ...rbie/faster-rr/src/syntax/types.rkt:18:0 --------------------------------------------------------------------- for-loop [100] 100.0% [102] 200(3.4%) 200(3.4%) add-local-binding!39 (unknown source) ---------------------------------------------------------------------