Profiling results ----------------- Total cpu time observed: 9204ms (out of 9300ms) Number of samples taken: 48 (once every 192ms) ====================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ====================================================================== [1] 9204(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [7] 100.0% ---------------------------------------------------------------------- ??? [73] 100.0% [2] 510(5.5%) 0(0.0%) approximate9 ...-pow-test/src/core/taylor.rkt:11:0 loop [8] 100.0% ---------------------------------------------------------------------- hash-ref! [82] 100.0% [3] 312(3.4%) 0(0.0%) ??? ...bie/ival-pow-test/src/core/taylor.rkt:67:15 ??? [73] 100.0% ---------------------------------------------------------------------- hash-ref! [82] 100.0% [4] 312(3.4%) 0(0.0%) f40 (unknown source) map [10] 100.0% ---------------------------------------------------------------------- hash-ref! [82] 100.0% [5] 312(3.4%) 0(0.0%) ??? ...ie/ival-pow-test/src/core/taylor.rkt:290:26 for-loop [9] 100.0% ---------------------------------------------------------------------- matcher [84] 100.0% [6] 220(2.4%) 0(0.0%) ??? ...e/ival-pow-test/src/core/matcher.rkt:127:11 for-loop [11] 100.0% ---------------------------------------------------------------------- ??? [1] 100.0% [7] 9204(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [12] 100.0% ---------------------------------------------------------------------- approximate9 [2] 100.0% [8] 510(5.5%) 0(0.0%) loop ...bie/ival-pow-test/src/core/taylor.rkt:86:4 hash-ref! [82] 61.2% ??? [73] 38.8% ---------------------------------------------------------------------- ??? [5] 100.0% [9] 312(3.4%) 0(0.0%) for-loop ...al-pow-test/src/core/taylor.rkt:290:52 hash-ref! [82] 100.0% ---------------------------------------------------------------------- f40 [4] 100.0% [10] 312(3.4%) 0(0.0%) map ...t-7.5/collects/racket/private/map.rkt:35:13 f61 [13] 100.0% ---------------------------------------------------------------------- ??? [6] 100.0% [11] 220(2.4%) 0(0.0%) for-loop ...l-pow-test/src/core/matcher.rkt:129:15 matcher [84] 100.0% ---------------------------------------------------------------------- profile-thunk16 [7] 100.0% [12] 9204(100.0%) 0(0.0%) run ...et-7.5/share/pkgs/profile-lib/main.rkt:39:2 .../more-scheme.rkt:261:28 [14]100.0% ---------------------------------------------------------------------- map [10] 100.0% [13] 312(3.4%) 0(0.0%) f61 (unknown source) eval-const-expr [15] 100.0% ---------------------------------------------------------------------- for-loop [78] 4.6% run [12] 95.4% [14] 9204(100.0%) 152(1.7%) .../more-scheme.rkt:261:28 (unknown source) prepare-points-intervals [16] 65.3% run-improve47 [17] 22.0% errors [71] 4.1% get-final-combination [19] 2.3% ordinary-value? [18] 2.0% ??? [46] 1.8% oracle-error [20] 1.6% ---------------------------------------------------------------------- f61 [13] 100.0% [15] 312(3.4%) 0(0.0%) eval-const-expr ...pow-test/src/programs.rkt:120:0 temp85_0 [79] 100.0% ---------------------------------------------------------------------- .../more-scheme.rkt:261:28 [14]100.0% [16] 6014(65.3%) 0(0.0%) prepare-points-intervals ...t/src/points.rkt:120:0 loop [21] 100.0% ---------------------------------------------------------------------- .../more-scheme.rkt:261:28 [14]100.0% [17] 2230(24.2%) 0(0.0%) run-improve47 ...l-pow-test/src/mainloop.rkt:346:0 for-loop [22] 100.0% ---------------------------------------------------------------------- .../more-scheme.rkt:261:28 [14]100.0% [18] 370(4.0%) 220(2.4%) ordinary-value? ...val-pow-test/src/float.rkt:51:0 list-member? [24] 40.5% ---------------------------------------------------------------------- .../more-scheme.rkt:261:28 [14]100.0% [19] 208(2.3%) 0(0.0%) get-final-combination ...st/src/mainloop.rkt:371:0 infer-splitpoints [23] 100.0% ---------------------------------------------------------------------- .../more-scheme.rkt:261:28 [14]100.0% [20] 150(1.6%) 0(0.0%) oracle-error ...ival-pow-test/src/points.rkt:177:0 for-loop [25] 100.0% ---------------------------------------------------------------------- prepare-points-intervals [16] 100.0% [21] 6014(65.3%) 374(4.1%) loop .../herbie/ival-pow-test/src/points.rkt:132:4 loop [26] 75.1% ??? [28] 18.7% ---------------------------------------------------------------------- run-improve47 [17] 100.0% [22] 2230(24.2%) 0(0.0%) for-loop ...e/ival-pow-test/src/mainloop.rkt:365:4 run-iter! [27] 100.0% ---------------------------------------------------------------------- get-final-combination [19] 100.0% [23] 208(2.3%) 0(0.0%) infer-splitpoints ...est/src/core/regimes.rkt:33:0 for-loop [29] 100.0% ---------------------------------------------------------------------- ordinary-value? [18] 100.0% [24] 150(1.6%) 150(1.6%) list-member? ...llects/racket/private/set.rkt:24:0 ---------------------------------------------------------------------- oracle-error [20] 100.0% [25] 150(1.6%) 0(0.0%) for-loop ...bie/ival-pow-test/src/points.rkt:178:2 loop [30] 100.0% ---------------------------------------------------------------------- loop [21] 100.0% [26] 4518(49.1%) 182(2.0%) loop ...s/herbie/ival-pow-test/src/points.rkt:79:2 ??? [31] 92.2% log! [38] 3.8% ---------------------------------------------------------------------- for-loop [22] 100.0% [27] 2230(24.2%) 0(0.0%) run-iter! .../ival-pow-test/src/mainloop.rkt:327:0 finalize-iter! [32] 43.6% simplify! [34] 23.7% gen-series! [35] 22.9% gen-rewrites! [36] 9.9% ---------------------------------------------------------------------- loop [21] 100.0% [28] 1122(12.2%) 218(2.4%) ??? ...s/herbie/ival-pow-test/src/points.rkt:113:6 sample-multi-bounded [33] 80.6% ---------------------------------------------------------------------- infer-splitpoints [23] 100.0% [29] 208(2.3%) 0(0.0%) for-loop ...val-pow-test/src/core/regimes.rkt:45:6 option-on-expr [37] 100.0% ---------------------------------------------------------------------- for-loop [25] 100.0% [30] 150(1.6%) 0(0.0%) loop (unknown source) ??? [39] 100.0% ---------------------------------------------------------------------- loop [26] 100.0% [31] 4166(45.3%) 734(8.0%) ??? ...tract/private/arrow-higher-order.rkt:379:33 ival-exp [40] 50.6% ival-div [42] 22.4% ival-add [47] 9.4% ---------------------------------------------------------------------- run-iter! [27] 100.0% [32] 972(10.6%) 0(0.0%) finalize-iter! ...-pow-test/src/mainloop.rkt:278:0 ??? [41] 100.0% ---------------------------------------------------------------------- ??? [28] 100.0% [33] 904(9.8%) 0(0.0%) sample-multi-bounded ...w-test/src/points.rkt:14:0 random-ranges [43] 58.6% for-loop [48] 41.4% ---------------------------------------------------------------------- run-iter! [27] 100.0% [34] 528(5.7%) 0(0.0%) simplify! .../ival-pow-test/src/mainloop.rkt:224:0 ??? [44] 100.0% ---------------------------------------------------------------------- run-iter! [27] 100.0% [35] 510(5.5%) 0(0.0%) gen-series! ...val-pow-test/src/mainloop.rkt:160:0 for-loop [45] 100.0% ---------------------------------------------------------------------- run-iter! [27] 100.0% [36] 220(2.4%) 0(0.0%) gen-rewrites! ...l-pow-test/src/mainloop.rkt:183:0 for-loop [49] 100.0% ---------------------------------------------------------------------- for-loop [29] 100.0% [37] 208(2.3%) 0(0.0%) option-on-expr ...-test/src/core/regimes.rkt:124:0 sort-context-on-expr [50] 100.0% ---------------------------------------------------------------------- loop [26] 100.0% [38] 170(1.8%) 0(0.0%) log! ...s/herbie/ival-pow-test/src/points.rkt:59:2 for-loop [51] 100.0% ---------------------------------------------------------------------- loop [30] 100.0% [39] 150(1.6%) 0(0.0%) ??? .../herbie/ival-pow-test/src/points.rkt:179:26 ??? [46] 100.0% ---------------------------------------------------------------------- ??? [31] 100.0% [40] 2108(22.9%) 1754(19.1%) ival-exp ...val-pow-test/src/biginterval.rkt:192:0 bfexp [56] 16.8% ---------------------------------------------------------------------- finalize-iter! [32] 100.0% [41] 972(10.6%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:486:18 for-loop [53] 80.2% atab-add-altns [60] 19.8% ---------------------------------------------------------------------- ??? [31] 100.0% [42] 932(10.1%) 344(3.7%) ival-div ...val-pow-test/src/biginterval.rkt:156:0 bfadd [52] 63.1% ---------------------------------------------------------------------- sample-multi-bounded [33] 100.0% [43] 530(5.8%) 156(1.7%) random-ranges ...val-pow-test/src/common.rkt:164:0 cons/c [59] 37.7% build--> [61] 32.8% ---------------------------------------------------------------------- simplify! [34] 100.0% [44] 528(5.7%) 0(0.0%) ??? ...ie/ival-pow-test/src/core/simplify.rkt:42:0 egraph-run [54] 100.0% ---------------------------------------------------------------------- gen-series! [35] 100.0% [45] 510(5.5%) 0(0.0%) for-loop ...e/ival-pow-test/src/mainloop.rkt:167:7 taylor-alt [55] 100.0% ---------------------------------------------------------------------- ??? [39] 31.1% .../more-scheme.rkt:261:28 [14] 68.9% [46] 482(5.2%) 482(5.2%) ??? .../ival-pow-test/src/syntax/syntax.rkt:214:17 ---------------------------------------------------------------------- ??? [31] 100.0% [47] 392(4.3%) 0(0.0%) ival-add ...val-pow-test/src/biginterval.rkt:102:0 bfadd [52] 100.0% ---------------------------------------------------------------------- sample-multi-bounded [33] 100.0% [48] 374(4.1%) 374(4.1%) for-loop ...rbie/ival-pow-test/src/points.rkt:20:4 ---------------------------------------------------------------------- gen-rewrites! [36] 100.0% [49] 220(2.4%) 0(0.0%) for-loop .../ival-pow-test/src/mainloop.rkt:191:11 rewrite-expression-head24 [57] 100.0% ---------------------------------------------------------------------- option-on-expr [37] 100.0% [50] 208(2.3%) 0(0.0%) sort-context-on-expr ...src/core/regimes.rkt:117:0 ??? [58] 100.0% ---------------------------------------------------------------------- log! [38] 100.0% [51] 170(1.8%) 170(1.8%) for-loop ...bie/ival-pow-test/src/points.rkt:66:23 ---------------------------------------------------------------------- ival-add [47] 40.0% ival-div [42] 60.0% [52] 980(10.6%) 0(0.0%) bfadd ...-lib/math/private/bigfloat/mpfr.rkt:835:4 new-mpfr [64] 59.4% ??? [63] 40.6% ---------------------------------------------------------------------- ??? [41] 100.0% [53] 780(8.5%) 0(0.0%) for-loop ...l-pow-test/src/core/alt-table.rkt:46:2 atab-add-altn [62] 75.4% loop [69] 24.6% ---------------------------------------------------------------------- ??? [44] 100.0% [54] 528(5.7%) 0(0.0%) egraph-run .../pkgs/egg-herbie-linux/main.rkt:49:0 egraph-add-exprs [65] 100.0% ---------------------------------------------------------------------- for-loop [45] 100.0% [55] 510(5.5%) 0(0.0%) taylor-alt ...ival-pow-test/src/mainloop.rkt:146:0 for-loop [66] 100.0% ---------------------------------------------------------------------- ival-exp [40] 100.0% [56] 354(3.8%) 0(0.0%) bfexp ...-lib/math/private/bigfloat/mpfr.rkt:632:4 ??? [63] 100.0% ---------------------------------------------------------------------- for-loop [49] 100.0% [57] 220(2.4%) 0(0.0%) rewrite-expression-head24 ...core/matcher.rkt:85:0 rewriter [67] 100.0% ---------------------------------------------------------------------- sort-context-on-expr [50] 100.0% [58] 208(2.3%) 0(0.0%) ??? ...-7.5/collects/racket/private/sort.rkt:369:3 generic-sort/key [68] 100.0% ---------------------------------------------------------------------- random-ranges [43] 100.0% [59] 200(2.2%) 200(2.2%) cons/c .../racket/contract/private/guts.rkt:500:15 ---------------------------------------------------------------------- ??? [41] 100.0% [60] 192(2.1%) 0(0.0%) atab-add-altns ...test/src/core/alt-table.rkt:40:0 atab-add-altn [62] 100.0% ---------------------------------------------------------------------- random-ranges [43] 100.0% [61] 174(1.9%) 0(0.0%) build--> ...act/private/arrow-val-first.rkt:1214:0 coerce-contract [70] 100.0% ---------------------------------------------------------------------- atab-add-altns [60] 24.6% for-loop [53] 75.4% [62] 780(8.5%) 0(0.0%) atab-add-altn ...test/src/core/alt-table.rkt:194:0 errors [71] 100.0% ---------------------------------------------------------------------- bfexp [56] 47.1% bfadd [52] 52.9% [63] 752(8.2%) 752(8.2%) ??? /opt/racket-7.5/collects/ffi/unsafe.rkt:938:4 ---------------------------------------------------------------------- bfadd [52] 100.0% [64] 582(6.3%) 582(6.3%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:348:0 ---------------------------------------------------------------------- egraph-run [54] 100.0% [65] 528(5.7%) 0(0.0%) egraph-add-exprs ...gg-herbie-linux/main.rkt:111:0 ??? [72] 100.0% ---------------------------------------------------------------------- taylor-alt [55] 100.0% [66] 510(5.5%) 0(0.0%) for-loop ...e/ival-pow-test/src/mainloop.rkt:152:6 ??? [73] 100.0% ---------------------------------------------------------------------- matcher [84] 50.0% rewrite-expression-head24 [57] 50.0% [67] 220(2.4%) 0(0.0%) rewriter ...val-pow-test/src/core/matcher.rkt:87:2 for-loop [74] 100.0% ---------------------------------------------------------------------- ??? [58] 100.0% [68] 208(2.3%) 0(0.0%) generic-sort/key .../racket/private/sort.rkt:156:2 copying-mergesort [75] 100.0% ---------------------------------------------------------------------- for-loop [53] 100.0% [69] 192(2.1%) 0(0.0%) loop ...ival-pow-test/src/core/alt-table.rkt:168:2 loop [76] 100.0% ---------------------------------------------------------------------- build--> [61] 100.0% [70] 174(1.9%) 0(0.0%) coerce-contract ...contract/private/guts.rkt:307:0 coerce-contract/f [77] 100.0% ---------------------------------------------------------------------- .../more-scheme.rkt:261:28 [14] 43.6% atab-add-altn [62] 56.4% [71] 1382(15.0%) 0(0.0%) errors ...erbie/ival-pow-test/src/points.rkt:190:0 for-loop [78] 72.8% temp85_0 [79] 27.2% ---------------------------------------------------------------------- egraph-add-exprs [65] 100.0% [72] 528(5.7%) 0(0.0%) ??? ...e/ival-pow-test/src/core/simplify.rkt:102:6 for-loop [80] 100.0% ---------------------------------------------------------------------- loop [8] 12.9% ??? [3] 20.4% location-do [81] 33.3% for-loop [66] 33.3% [73] 510(5.5%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:375:33 location-do [81] 33.3% approximate9 [2] 33.3% hash-ref! [82] 33.3% ---------------------------------------------------------------------- rewriter [67] 100.0% [74] 220(2.4%) 0(0.0%) for-loop ...val-pow-test/src/core/matcher.rkt:89:4 matcher [84] 50.0% for-loop [83] 50.0% ---------------------------------------------------------------------- generic-sort/key [68] 25.0% copying-mergesort [75] 75.0% [75] 208(2.3%) 0(0.0%) copying-mergesort ...racket/private/sort.rkt:129:8 copying-mergesort [75] 75.0% jloop [85] 25.0% ---------------------------------------------------------------------- loop [69] 100.0% [76] 192(2.1%) 192(2.1%) loop .../ival-pow-test/src/core/alt-table.rkt:92:2 ---------------------------------------------------------------------- coerce-contract [70] 100.0% [77] 174(1.9%) 0(0.0%) coerce-contract/f ...ntract/private/guts.rkt:372:0 coerce-simple-value [86] 100.0% ---------------------------------------------------------------------- errors [71] 100.0% [78] 1006(10.9%) 152(1.7%) for-loop ...bie/ival-pow-test/src/points.rkt:192:2 .../more-scheme.rkt:261:28 [14] 84.9% ---------------------------------------------------------------------- eval-const-expr [15] 45.3% errors [71] 54.7% [79] 688(7.5%) 0(0.0%) temp85_0 (unknown source) compile15 [87] 100.0% ---------------------------------------------------------------------- ??? [72] 100.0% [80] 528(5.7%) 528(5.7%) for-loop ...l-pow-test/src/core/simplify.rkt:121:2 ---------------------------------------------------------------------- location-do [81] 30.6% ??? [73] 69.4% [81] 510(5.5%) 0(0.0%) location-do ...ival-pow-test/src/programs.rkt:63:0 ??? [73] 69.4% location-do [81] 30.6% ---------------------------------------------------------------------- for-loop [9] 15.3% ??? [88] 15.3% loop [8] 15.3% ??? [73] 54.1% [82] 510(5.5%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 simplify* [90] 38.8% ??? [3] 15.3% ??? [88] 15.3% ??? [5] 15.3% f40 [4] 15.3% ---------------------------------------------------------------------- for-loop [74] 100.0% [83] 220(2.4%) 0(0.0%) for-loop ...val-pow-test/src/core/matcher.rkt:96:8 fix-up-variables [89] 100.0% ---------------------------------------------------------------------- for-loop [11] 50.0% for-loop [74] 50.0% [84] 220(2.4%) 0(0.0%) matcher ...val-pow-test/src/core/matcher.rkt:115:2 ??? [6] 50.0% rewriter [67] 50.0% ---------------------------------------------------------------------- copying-mergesort [75] 100.0% [85] 208(2.3%) 208(2.3%) jloop ...5/collects/racket/private/sort.rkt:120:23 ---------------------------------------------------------------------- coerce-contract/f [77] 100.0% [86] 174(1.9%) 174(1.9%) coerce-simple-value ...ract/private/guts.rkt:385:0 ---------------------------------------------------------------------- temp85_0 [79] 100.0% [87] 688(7.5%) 0(0.0%) compile15 (unknown source) temp91_0 [91] 100.0% ---------------------------------------------------------------------- hash-ref! [82] 100.0% [88] 312(3.4%) 0(0.0%) ??? ...bie/ival-pow-test/src/core/taylor.rkt:44:15 hash-ref! [82] 100.0% ---------------------------------------------------------------------- for-loop [83] 100.0% [89] 220(2.4%) 0(0.0%) fix-up-variables ...est/src/core/matcher.rkt:108:2 pattern-substitute [92] 100.0% ---------------------------------------------------------------------- hash-ref! [82] 100.0% [90] 198(2.2%) 198(2.2%) simplify* ...val-pow-test/src/core/reduce.rkt:29:0 ---------------------------------------------------------------------- compile15 [87] 100.0% [91] 688(7.5%) 0(0.0%) temp91_0 (unknown source) temp118_0 [93] 100.0% ---------------------------------------------------------------------- pattern-substitute [92] 50.0% fix-up-variables [89] 50.0% [92] 220(2.4%) 0(0.0%) pattern-substitute ...st/src/core/matcher.rkt:45:0 pattern-substitute [92] 50.0% do-curry [94] 50.0% ---------------------------------------------------------------------- temp91_0 [91] 100.0% [93] 688(7.5%) 0(0.0%) temp118_0 (unknown source) expand-capturing-lifts [95] 100.0% ---------------------------------------------------------------------- pattern-substitute [92] 100.0% [94] 220(2.4%) 220(2.4%) do-curry ...-7.5/collects/racket/function.rkt:71:2 ---------------------------------------------------------------------- temp118_0 [93] 100.0% [95] 688(7.5%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [96] 100.0% ---------------------------------------------------------------------- expand-capturing-lifts [95] 100.0% [96] 688(7.5%) 0(0.0%) lambda-clause-expander (unknown source) finish-bodys [97] 100.0% ---------------------------------------------------------------------- lambda-clause-expander [96] 44.2% for-loop [98] 55.8% [97] 688(7.5%) 0(0.0%) finish-bodys (unknown source) for-loop [98] 100.0% ---------------------------------------------------------------------- for-loop [98] 38.2% finish-bodys [97] 61.8% [98] 688(7.5%) 0(0.0%) for-loop (unknown source) finish-bodys [97] 38.7% for-loop [98] 38.2% expand-implicit [101] 13.4% add-local-binding!39 [99] 7.6% dispatch-variable [100] 2.1% ---------------------------------------------------------------------- for-loop [98] 100.0% [99] 312(3.4%) 312(3.4%) add-local-binding!39 (unknown source) ---------------------------------------------------------------------- for-loop [98] 100.0% [100] 192(2.1%) 0(0.0%) dispatch-variable (unknown source) substitute-variable6 [102] 100.0% ---------------------------------------------------------------------- for-loop [98] 100.0% [101] 184(2.0%) 0(0.0%) expand-implicit (unknown source) resolve+shift28 [103] 100.0% ---------------------------------------------------------------------- dispatch-variable [100] 100.0% [102] 192(2.1%) 192(2.1%) substitute-variable6 (unknown source) ---------------------------------------------------------------------- expand-implicit [101] 100.0% [103] 184(2.0%) 0(0.0%) resolve+shift28 (unknown source) fallback-loop [104] 100.0% ---------------------------------------------------------------------- resolve+shift28 [103] 100.0% [104] 184(2.0%) 184(2.0%) fallback-loop (unknown source) ----------------------------------------------------------------------