Profiling results ----------------- Total cpu time observed: 18034ms (out of 18732ms) Number of samples taken: 49 (once every 368ms) ======================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ======================================================================== ??? [11] 100.0% [1] 18034(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [6] 100.0% ------------------------------------------------------------------------ ??? [11] 100.0% [2] 13174(73.1%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:242:2 make-exacts-intervals [7] 92.0% for-loop [9] 8.0% ------------------------------------------------------------------------ ??? [11] 100.0% [3] 2976(16.5%) 0(0.0%) run-improve39 ...ie/develop/src/mainloop.rkt:322:0 for-loop [8] 100.0% ------------------------------------------------------------------------ ??? [11] 100.0% [4] 306(1.7%) 0(0.0%) get-final-combination ...op/src/mainloop.rkt:345:0 ??? [10] 100.0% ------------------------------------------------------------------------ hash-ref! [54] 100.0% [5] 296(1.6%) 0(0.0%) ??? ...tlies/herbie/develop/src/programs.rkt:167:5 loop [49] 100.0% ------------------------------------------------------------------------ profile-thunk16 [1] 100.0% [6] 18034(100.0%) 0(0.0%) run ...racket/share/pkgs/profile-lib/main.rkt:39:2 ??? [11] 100.0% ------------------------------------------------------------------------ loop [2] 100.0% [7] 12120(67.2%) 0(0.0%) make-exacts-intervals ...elop/src/points.rkt:183:0 for-loop [12] 97.1% loop [16] 2.9% ------------------------------------------------------------------------ run-improve39 [3] 100.0% [8] 2976(16.5%) 0(0.0%) for-loop ...herbie/develop/src/mainloop.rkt:339:10 run-iter! [13] 100.0% ------------------------------------------------------------------------ loop [2] 100.0% [9] 1054(5.8%) 0(0.0%) for-loop ...s/herbie/develop/src/points.rkt:257:19 for-loop [15] 71.9% ??? [51] 28.1% ------------------------------------------------------------------------ get-final-combination [4] 33.9% ??? [52] 66.1% [10] 902(5.0%) 0(0.0%) ??? ...s/herbie/develop/src/core/simplify.rkt:31:0 iterate-egraph!7 [14] 100.0% ------------------------------------------------------------------------ run [6] 50.0% [11] 18034(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 50.0% loop [2] 36.5% run-improve39 [3] 8.3% eval-errors [39] 3.5% oracle-error [21] 0.9% get-final-combination [4] 0.8% ------------------------------------------------------------------------ make-exacts-intervals [7] 100.0% [12] 11764(65.2%) 390(2.2%) for-loop ...es/herbie/develop/src/points.rkt:186:2 loop [16] 96.7% ------------------------------------------------------------------------ for-loop [8] 100.0% [13] 2976(16.5%) 0(0.0%) run-iter! ...herbie/develop/src/mainloop.rkt:303:0 finalize-iter! [17] 60.1% simplify! [19] 20.0% gen-rewrites! [20] 19.8% ------------------------------------------------------------------------ ??? [10] 100.0% [14] 902(5.0%) 0(0.0%) iterate-egraph!7 ...lop/src/core/simplify.rkt:61:0 one-iter [18] 100.0% ------------------------------------------------------------------------ for-loop [9] 100.0% [15] 758(4.2%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:234:4 ??? [52] 100.0% ------------------------------------------------------------------------ make-exacts-intervals [7] 3.0% for-loop [12] 97.0% [16] 11730(65.0%) 298(1.7%) loop ...htlies/herbie/develop/src/points.rkt:168:2 ??? [46] 94.8% ??? [52] 2.7% ------------------------------------------------------------------------ run-iter! [13] 100.0% [17] 1790(9.9%) 0(0.0%) finalize-iter! ...e/develop/src/mainloop.rkt:257:0 ??? [22] 100.0% ------------------------------------------------------------------------ iterate-egraph!7 [14] 100.0% [18] 902(5.0%) 0(0.0%) one-iter ...bie/develop/src/core/simplify.rkt:72:0 for-loop [23] 100.0% ------------------------------------------------------------------------ run-iter! [13] 100.0% [19] 596(3.3%) 0(0.0%) simplify! ...herbie/develop/src/mainloop.rkt:206:0 for-loop [24] 100.0% ------------------------------------------------------------------------ run-iter! [13] 100.0% [20] 590(3.3%) 0(0.0%) gen-rewrites! ...ie/develop/src/mainloop.rkt:177:0 for-loop [25] 100.0% ------------------------------------------------------------------------ ??? [11] 100.0% [21] 312(1.7%) 0(0.0%) oracle-error ...erbie/develop/src/points.rkt:311:0 for-loop [26] 100.0% ------------------------------------------------------------------------ match-e [28] 14.4% finalize-iter! [17] 85.6% [22] 2090(11.6%) 300(1.7%) ??? ...contract/private/arrow-val-first.rkt:388:18 for-loop [27] 85.6% ------------------------------------------------------------------------ for-loop [23] 50.0% one-iter [18] 50.0% [23] 902(5.0%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:83:12 for-loop [23] 50.0% match-e [28] 50.0% ------------------------------------------------------------------------ simplify! [19] 100.0% [24] 596(3.3%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:213:6 for-loop [29] 100.0% ------------------------------------------------------------------------ gen-rewrites! [20] 100.0% [25] 590(3.3%) 0(0.0%) for-loop ...herbie/develop/src/mainloop.rkt:184:11 ??? [30] 100.0% ------------------------------------------------------------------------ oracle-error [21] 100.0% [26] 312(1.7%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:312:2 ??? [31] 100.0% ------------------------------------------------------------------------ ??? [22] 100.0% [27] 1790(9.9%) 0(0.0%) for-loop ...ie/develop/src/core/alt-table.rkt:51:2 atab-add-altn [32] 100.0% ------------------------------------------------------------------------ for-loop [38] 16.6% for-loop [23] 83.4% [28] 902(5.0%) 296(1.6%) match-e ...herbie/develop/src/core/ematch.rkt:39:0 for-loop [33] 50.6% ??? [22] 16.6% ------------------------------------------------------------------------ for-loop [24] 100.0% [29] 596(3.3%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:237:8 ??? [52] 100.0% ------------------------------------------------------------------------ for-loop [25] 100.0% [30] 590(3.3%) 0(0.0%) ??? ...ies/herbie/develop/src/alternative.rkt:54:0 rewriter [34] 100.0% ------------------------------------------------------------------------ for-loop [26] 100.0% [31] 312(1.7%) 312(1.7%) ??? ...ket/collects/racket/private/for.rkt:1208:14 ------------------------------------------------------------------------ for-loop [27] 100.0% [32] 1790(9.9%) 0(0.0%) atab-add-altn ...elop/src/core/alt-table.rkt:210:0 override-at-pnts [35] 66.7% best-and-tied-at-points [36] 33.3% ------------------------------------------------------------------------ match-e [28] 100.0% [33] 606(3.4%) 306(1.7%) for-loop ...rbie/develop/src/core/ematch.rkt:52:11 for-loop [38] 49.5% ------------------------------------------------------------------------ matcher [42] 24.7% ??? [30] 75.3% [34] 590(3.3%) 292(1.6%) rewriter ...bie/develop/src/core/matcher.rkt:131:2 for-loop [37] 75.3% ------------------------------------------------------------------------ atab-add-altn [32] 100.0% [35] 1194(6.6%) 0(0.0%) override-at-pnts ...p/src/core/alt-table.rkt:145:0 eval-errors [39] 50.3% eval-prog [40] 49.7% ------------------------------------------------------------------------ atab-add-altn [32] 100.0% [36] 596(3.3%) 0(0.0%) best-and-tied-at-points ...ore/alt-table.rkt:122:0 eval-errors [39] 50.3% eval-prog [40] 49.7% ------------------------------------------------------------------------ rewriter [34] 100.0% [37] 590(3.3%) 0(0.0%) for-loop ...ie/develop/src/core/matcher.rkt:134:10 type-of [41] 50.5% matcher [42] 49.5% ------------------------------------------------------------------------ for-loop [33] 100.0% [38] 300(1.7%) 0(0.0%) for-loop ...rbie/develop/src/core/ematch.rkt:58:37 match-e [28] 100.0% ------------------------------------------------------------------------ best-and-tied-at-points [36] 13.9% override-at-pnts [35] 27.7% ??? [11] 58.4% [39] 2166(12.0%) 0(0.0%) eval-errors ...herbie/develop/src/points.rkt:292:0 for-loop [43] 100.0% ------------------------------------------------------------------------ best-and-tied-at-points [36] 33.3% override-at-pnts [35] 66.7% [40] 890(4.9%) 0(0.0%) eval-prog ...herbie/develop/src/programs.rkt:108:0 temp68_2 [44] 66.7% hash-ref! [54] 33.3% ------------------------------------------------------------------------ for-loop [37] 100.0% [41] 298(1.7%) 298(1.7%) type-of .../herbie/develop/src/type-check.rkt:34:0 ------------------------------------------------------------------------ for-loop [45] 50.0% for-loop [37] 50.0% [42] 292(1.6%) 0(0.0%) matcher ...rbie/develop/src/core/matcher.rkt:168:2 for-loop [45] 50.0% rewriter [34] 50.0% ------------------------------------------------------------------------ eval-errors [39] 100.0% [43] 2166(12.0%) 910(5.0%) for-loop ...es/herbie/develop/src/points.rkt:294:2 ??? [52] 29.5% ??? [46] 28.4% ------------------------------------------------------------------------ eval-prog [40] 100.0% [44] 594(3.3%) 0(0.0%) temp68_2 (unknown source) compile16 [47] 100.0% ------------------------------------------------------------------------ matcher [42] 100.0% [45] 292(1.6%) 0(0.0%) for-loop ...ie/develop/src/core/matcher.rkt:184:21 matcher [42] 100.0% ------------------------------------------------------------------------ for-loop [43] 5.2% loop [16] 94.8% [46] 11736(65.1%) 0(0.0%) ??? ...tlies/herbie/develop/src/programs.rkt:124:2 ??? [48] 70.5% ??? [51] 21.6% loop [49] 5.4% ??? [52] 2.6% ------------------------------------------------------------------------ temp68_2 [44] 100.0% [47] 594(3.3%) 0(0.0%) compile16 (unknown source) temp74_0 [50] 100.0% ------------------------------------------------------------------------ ??? [46] 100.0% [48] 8270(45.9%) 0(0.0%) ??? ...acket/collects/racket/private/kw.rkt:592:14 ??? [51] 100.0% ------------------------------------------------------------------------ ??? [5] 31.8% ??? [46] 68.2% [49] 932(5.2%) 0(0.0%) loop ...cket/collects/racket/private/map.rkt:40:19 ??? [51] 34.3% ??? [52] 33.9% hash-ref! [54] 31.8% ------------------------------------------------------------------------ compile16 [47] 100.0% [50] 594(3.3%) 0(0.0%) temp74_0 (unknown source) expand-single [53] 100.0% ------------------------------------------------------------------------ for-loop [9] 2.6% loop [49] 2.8% ??? [46] 22.2% ??? [48] 72.4% [51] 11416(63.3%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:361:33 ival-mult [55] 72.4% mk-ival [56] 25.0% ??? [71] 2.6% ------------------------------------------------------------------------ location-do [59] 10.2% for-loop [29] 10.2% ??? [46] 10.3% loop [16] 10.7% loop [49] 10.8% for-loop [43] 21.9% for-loop [15] 25.9% [52] 2922(16.2%) 318(1.8%) ??? ...tract/private/arrow-higher-order.rkt:357:33 ->flonum [57] 42.8% sample-multi-bounded [58] 25.9% location-do [59] 10.2% ??? [10] 10.2% ------------------------------------------------------------------------ temp74_0 [50] 100.0% [53] 594(3.3%) 0(0.0%) expand-single (unknown source) expand-capturing-lifts [60] 100.0% ------------------------------------------------------------------------ loop [49] 50.0% eval-prog [40] 50.0% [54] 296(1.6%) 296(1.6%) hash-ref! .../racket/private/more-scheme.rkt:376:2 ??? [5] 50.0% ------------------------------------------------------------------------ ??? [51] 100.0% [55] 8270(45.9%) 0(0.0%) ival-mult ...rbie/develop/src/biginterval.rkt:94:0 bf* [61] 40.1% bfmax* [62] 32.6% ...higher-order.rkt:361:33 [64] 23.7% bfmin* [69] 3.6% ------------------------------------------------------------------------ ??? [51] 100.0% [56] 2850(15.8%) 674(3.7%) mk-ival ...herbie/develop/src/biginterval.rkt:52:0 bf [63] 76.4% ------------------------------------------------------------------------ ??? [52] 100.0% [57] 1250(6.9%) 928(5.1%) ->flonum ...s/herbie/develop/src/programs.rkt:36:0 ??? [67] 25.8% ------------------------------------------------------------------------ ??? [52] 100.0% [58] 758(4.2%) 0(0.0%) sample-multi-bounded ...evelop/src/points.rkt:44:0 loop [66] 59.1% for-loop [68] 40.9% ------------------------------------------------------------------------ location-do [59] 25.2% ??? [52] 74.8% [59] 596(3.3%) 0(0.0%) location-do ...erbie/develop/src/programs.rkt:89:0 ??? [52] 74.8% location-do [59] 25.2% ------------------------------------------------------------------------ expand-single [53] 100.0% [60] 594(3.3%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [65] 100.0% ------------------------------------------------------------------------ ival-mult [55] 100.0% [61] 3320(18.4%) 330(1.8%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [75] 90.1% ------------------------------------------------------------------------ ival-mult [55] 100.0% [62] 2694(14.9%) 454(2.5%) bfmax* .../herbie/develop/src/biginterval.rkt:91:0 foldl [70] 83.1% ------------------------------------------------------------------------ mk-ival [56] 100.0% [63] 2176(12.1%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [81] 71.0% ??? [82] 29.0% ------------------------------------------------------------------------ ival-mult [55] 100.0% [64] 1958(10.9%) 0(0.0%) ...higher-order.rkt:361:33 (unknown source) ??? [71] 100.0% ------------------------------------------------------------------------ expand-capturing-lifts [60] 100.0% [65] 594(3.3%) 0(0.0%) lambda-clause-expander (unknown source) finish-bodys [72] 100.0% ------------------------------------------------------------------------ sample-multi-bounded [58] 100.0% [66] 448(2.5%) 0(0.0%) loop ...ghtlies/herbie/develop/src/points.rkt:66:6 random-exp [73] 100.0% ------------------------------------------------------------------------ ->flonum [57] 100.0% [67] 322(1.8%) 0(0.0%) ??? ...s/racket/contract/private/arr-i.rkt:1252:29 ??? [74] 100.0% ------------------------------------------------------------------------ sample-multi-bounded [58] 100.0% [68] 310(1.7%) 310(1.7%) for-loop ...ies/herbie/develop/src/points.rkt:47:4 ------------------------------------------------------------------------ ival-mult [55] 100.0% [69] 298(1.7%) 0(0.0%) bfmin* .../herbie/develop/src/biginterval.rkt:88:0 foldl [70] 100.0% ------------------------------------------------------------------------ bfmin* [69] 11.7% bfmax* [62] 88.3% [70] 2538(14.1%) 0(0.0%) foldl ...et/collects/racket/private/list.rkt:229:4 ??? [75] 100.0% ------------------------------------------------------------------------ ??? [51] 13.1% ...higher-order.rkt:361:33 [64] 86.9% [71] 2254(12.5%) 1554(8.6%) ??? ...cts/racket/contract/private/list.rkt:209:10 ??? [76] 31.1% ------------------------------------------------------------------------ lambda-clause-expander [65] 9.6% for-loop [77] 90.4% [72] 594(3.3%) 0(0.0%) finish-bodys (unknown source) for-loop [77] 100.0% ------------------------------------------------------------------------ loop [66] 100.0% [73] 448(2.5%) 448(2.5%) random-exp .../herbie/develop/src/common.rkt:230:0 ------------------------------------------------------------------------ ??? [67] 100.0% [74] 322(1.8%) 322(1.8%) ??? ...ects/racket/contract/private/prop.rkt:274:4 ------------------------------------------------------------------------ foldl [70] 45.9% bf* [61] 54.1% [75] 5528(30.7%) 346(1.9%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmax2 [78] 93.7% ------------------------------------------------------------------------ ??? [71] 100.0% [76] 700(3.9%) 700(3.9%) ??? ...ects/racket/contract/private/guts.rkt:746:8 ------------------------------------------------------------------------ for-loop [77] 8.7% finish-bodys [72] 91.3% [77] 594(3.3%) 0(0.0%) for-loop (unknown source) finish-bodys [72] 82.6% for-loop [77] 8.7% dispatch-transformer41 [79] 5.6% syntax-e [80] 3.1% ------------------------------------------------------------------------ ??? [75] 100.0% [78] 5182(28.7%) 0(0.0%) bfmax2 ...lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [81] 70.4% ??? [82] 29.6% ------------------------------------------------------------------------ for-loop [77] 100.0% [79] 298(1.7%) 0(0.0%) dispatch-transformer41 (unknown source) apply-transformer52 [83] 100.0% ------------------------------------------------------------------------ for-loop [77] 100.0% [80] 296(1.6%) 0(0.0%) syntax-e (unknown source) syntax-e/no-taint [84] 100.0% ------------------------------------------------------------------------ bf [63] 29.8% bfmax2 [78] 70.2% [81] 5196(28.8%) 5196(28.8%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ------------------------------------------------------------------------ bf [63] 29.1% bfmax2 [78] 70.9% [82] 2162(12.0%) 2162(12.0%) ??? /home/p92/racket/collects/ffi/unsafe.rkt:898:4 ------------------------------------------------------------------------ dispatch-transformer41 [79] 100.0% [83] 298(1.7%) 0(0.0%) apply-transformer52 (unknown source) apply-transformer-in-context [85]100.0% ------------------------------------------------------------------------ syntax-e [80] 100.0% [84] 296(1.6%) 0(0.0%) syntax-e/no-taint (unknown source) loop [86] 100.0% ------------------------------------------------------------------------ apply-transformer52 [83] 100.0% [85] 298(1.7%) 298(1.7%) apply-transformer-in-context (unknown source) ------------------------------------------------------------------------ syntax-e/no-taint [84] 100.0% [86] 296(1.6%) 0(0.0%) loop (unknown source) gf [87] 100.0% ------------------------------------------------------------------------ loop [86] 100.0% [87] 296(1.6%) 0(0.0%) gf (unknown source) propagation-apply [88] 100.0% ------------------------------------------------------------------------ gf [87] 100.0% [88] 296(1.6%) 296(1.6%) propagation-apply (unknown source) ------------------------------------------------------------------------