Profiling results ----------------- Total cpu time observed: 13446ms (out of 13524ms) Number of samples taken: 67 (once every 201ms) ========================================================================= Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ========================================================================= [1] 13446(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [8] 100.0% ------------------------------------------------------------------------- ??? [65] 100.0% [2] 1852(13.8%) 0(0.0%) approximate9 .../findroot/src/core/taylor.rkt:11:0 loop [9] 100.0% ------------------------------------------------------------------------- hash-ref! [73] 100.0% [3] 1030(7.7%) 0(0.0%) ??? .../herbie/findroot/src/core/taylor.rkt:300:23 for-loop [10] 100.0% ------------------------------------------------------------------------- for-loop [93] 100.0% [4] 818(6.1%) 0(0.0%) expand-implicit (unknown source) resolve+shift28 [12] 72.9% datum->syntax6 [15] 27.1% ------------------------------------------------------------------------- loop [98] 49.8% for-loop [93] 50.2% [5] 812(6.0%) 0(0.0%) syntax-e (unknown source) syntax-e/no-taint [11] 100.0% ------------------------------------------------------------------------- hash-ref! [73] 100.0% [6] 626(4.7%) 0(0.0%) ??? .../herbie/findroot/src/core/taylor.rkt:290:26 for-loop [13] 100.0% ------------------------------------------------------------------------- matcher [78] 100.0% [7] 424(3.2%) 0(0.0%) ??? ...herbie/findroot/src/core/matcher.rkt:127:11 for-loop [14] 100.0% ------------------------------------------------------------------------- ??? [1] 100.0% [8] 13446(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [16] 100.0% ------------------------------------------------------------------------- approximate9 [2] 100.0% [9] 1852(13.8%) 0(0.0%) loop ...s/herbie/findroot/src/core/taylor.rkt:86:4 ??? [65] 100.0% ------------------------------------------------------------------------- ??? [3] 100.0% [10] 1030(7.7%) 0(0.0%) for-loop ...ie/findroot/src/core/taylor.rkt:303:27 hash-ref! [73] 80.4% f40 [17] 19.6% ------------------------------------------------------------------------- expand-id-application-form17 [91] 19.9% syntax-e [5] 80.1% [11] 1014(7.5%) 0(0.0%) syntax-e/no-taint (unknown source) loop [98] 100.0% ------------------------------------------------------------------------- core-form-sym [100] 25.1% expand-implicit [4] 74.9% [12] 796(5.9%) 200(1.5%) resolve+shift28 (unknown source) fallback-loop [18] 74.9% ------------------------------------------------------------------------- ??? [6] 100.0% [13] 626(4.7%) 0(0.0%) for-loop ...ie/findroot/src/core/taylor.rkt:290:52 hash-ref! [73] 100.0% ------------------------------------------------------------------------- ??? [7] 100.0% [14] 424(3.2%) 0(0.0%) for-loop ...e/findroot/src/core/matcher.rkt:129:15 matcher [78] 100.0% ------------------------------------------------------------------------- expand-implicit [4] 100.0% [15] 222(1.7%) 0(0.0%) datum->syntax6 (unknown source) loop [98] 100.0% ------------------------------------------------------------------------- profile-thunk16 [8] 100.0% [16] 13446(100.0%) 0(0.0%) run ...et-7.5/share/pkgs/profile-lib/main.rkt:39:2 .../more-scheme.rkt:261:28 [19] 100.0% ------------------------------------------------------------------------- map [29] 12.2% for-loop [10] 24.2% hash-ref! [73] 63.6% [17] 836(6.2%) 0(0.0%) f40 (unknown source) map [29] 100.0% ------------------------------------------------------------------------- resolve+shift28 [12] 100.0% [18] 596(4.4%) 200(1.5%) fallback-loop (unknown source) for-loop [93] 66.4% ------------------------------------------------------------------------- for-loop [74] 3.0% run [16] 97.0% [19] 13446(100.0%) 200(1.5%) .../more-scheme.rkt:261:28 (unknown source) prepare-points-intervals [23] 54.9% run-improve47 [20] 35.8% errors [66] 3.2% oracle-error [21] 1.6% ??? [44] 1.5% get-p&es [22] 1.5% ??? [65] 0.8% ------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [19] 100.0% [20] 5016(37.3%) 0(0.0%) run-improve47 ...e/findroot/src/mainloop.rkt:346:0 for-loop [24] 86.4% simplify! [34] 9.5% prepare-points-intervals [23] 4.1% ------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [19] 100.0% [21] 216(1.6%) 0(0.0%) oracle-error ...rbie/findroot/src/points.rkt:177:0 for-loop [25] 100.0% ------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [19] 100.0% [22] 202(1.5%) 0(0.0%) get-p&es ...s/herbie/findroot/src/sandbox.rkt:25:0 for-loop [26] 100.0% ------------------------------------------------------------------------- run-improve47 [20] 2.7% .../more-scheme.rkt:261:28 [19] 97.3% [23] 7594(56.5%) 0(0.0%) prepare-points-intervals ...t/src/points.rkt:120:0 loop [27] 100.0% ------------------------------------------------------------------------- run-improve47 [20] 100.0% [24] 4332(32.2%) 0(0.0%) for-loop ...herbie/findroot/src/mainloop.rkt:365:4 run-iter! [28] 100.0% ------------------------------------------------------------------------- oracle-error [21] 100.0% [25] 216(1.6%) 0(0.0%) for-loop ...s/herbie/findroot/src/points.rkt:178:2 map [29] 100.0% ------------------------------------------------------------------------- get-p&es [22] 100.0% [26] 202(1.5%) 202(1.5%) for-loop ...s/herbie/findroot/src/sandbox.rkt:26:2 ------------------------------------------------------------------------- prepare-points-intervals [23] 100.0% [27] 7594(56.5%) 0(0.0%) loop ...tlies/herbie/findroot/src/points.rkt:132:4 loop [30] 95.2% ??? [36] 4.8% ------------------------------------------------------------------------- for-loop [24] 100.0% [28] 4332(32.2%) 0(0.0%) run-iter! ...erbie/findroot/src/mainloop.rkt:327:0 gen-series! [31] 42.8% finalize-iter! [32] 42.3% gen-rewrites! [35] 9.8% simplify! [34] 5.1% ------------------------------------------------------------------------- for-loop [25] 20.5% f40 [17] 79.5% [29] 1052(7.8%) 0(0.0%) map ...t-7.5/collects/racket/private/map.rkt:35:13 parse-loop74 [33] 50.6% ??? [37] 20.5% f61 [38] 19.2% f40 [17] 9.7% ------------------------------------------------------------------------- loop [27] 100.0% [30] 7226(53.7%) 0(0.0%) loop ...htlies/herbie/findroot/src/points.rkt:79:2 ??? [39] 75.1% ??? [65] 13.6% real->precision [45] 5.9% hash-update! [48] 2.8% ??? [40] 2.7% ------------------------------------------------------------------------- run-iter! [28] 100.0% [31] 1852(13.8%) 0(0.0%) gen-series! ...bie/findroot/src/mainloop.rkt:160:0 for-loop [42] 100.0% ------------------------------------------------------------------------- run-iter! [28] 100.0% [32] 1834(13.6%) 0(0.0%) finalize-iter! .../findroot/src/mainloop.rkt:278:0 ??? [40] 100.0% ------------------------------------------------------------------------- parse-loop74 [33] 8.1% map [29] 38.4% hash-ref! [73] 53.5% [33] 1650(12.3%) 0(0.0%) parse-loop74 (unknown source) eval-const-expr [41] 91.9% parse-loop74 [33] 8.1% ------------------------------------------------------------------------- run-iter! [28] 31.8% run-improve47 [20] 68.2% [34] 698(5.2%) 0(0.0%) simplify! ...erbie/findroot/src/mainloop.rkt:224:0 ??? [43] 100.0% ------------------------------------------------------------------------- run-iter! [28] 100.0% [35] 424(3.2%) 0(0.0%) gen-rewrites! ...e/findroot/src/mainloop.rkt:183:0 for-loop [46] 100.0% ------------------------------------------------------------------------- loop [27] 100.0% [36] 368(2.7%) 0(0.0%) ??? ...htlies/herbie/findroot/src/points.rkt:113:6 sample-multi-bounded [47] 100.0% ------------------------------------------------------------------------- map [29] 100.0% [37] 216(1.6%) 0(0.0%) ??? ...tlies/herbie/findroot/src/points.rkt:179:26 ??? [44] 100.0% ------------------------------------------------------------------------- map [29] 100.0% [38] 202(1.5%) 0(0.0%) f61 (unknown source) eval-const-expr [41] 100.0% ------------------------------------------------------------------------- loop [30] 100.0% [39] 5424(40.3%) 2338(17.4%) ??? ...tract/private/arrow-higher-order.rkt:379:33 ival-log [49] 49.5% ival-sub [55] 7.4% ------------------------------------------------------------------------- loop [30] 9.6% finalize-iter! [32] 90.4% [40] 2028(15.1%) 194(1.4%) ??? ...contract/private/arrow-val-first.rkt:486:18 for-loop [51] 80.6% atab-add-altns [57] 9.9% ------------------------------------------------------------------------- f61 [38] 10.9% parse-loop74 [33] 89.1% [41] 1852(13.8%) 0(0.0%) eval-const-expr ...findroot/src/programs.rkt:120:0 temp85_0 [71] 89.2% loop [98] 10.8% ------------------------------------------------------------------------- gen-series! [31] 100.0% [42] 1852(13.8%) 0(0.0%) for-loop ...herbie/findroot/src/mainloop.rkt:167:7 taylor-alt [50] 100.0% ------------------------------------------------------------------------- simplify! [34] 100.0% [43] 698(5.2%) 0(0.0%) ??? .../herbie/findroot/src/core/simplify.rkt:42:0 egraph-run [52] 100.0% ------------------------------------------------------------------------- ??? [37] 34.4% .../more-scheme.rkt:261:28 [19] 65.6% [44] 628(4.7%) 202(1.5%) ??? ...erbie/findroot/src/syntax/syntax.rkt:214:17 return/no-unsupplied [53] 67.8% ------------------------------------------------------------------------- loop [30] 100.0% [45] 428(3.2%) 0(0.0%) real->precision ...findroot/src/programs.rkt:94:11 ??? [65] 100.0% ------------------------------------------------------------------------- gen-rewrites! [35] 100.0% [46] 424(3.2%) 0(0.0%) for-loop ...erbie/findroot/src/mainloop.rkt:191:11 rewrite-expression-head24 [54] 100.0% ------------------------------------------------------------------------- ??? [36] 100.0% [47] 368(2.7%) 0(0.0%) sample-multi-bounded ...ndroot/src/points.rkt:14:0 random-ranges [56] 100.0% ------------------------------------------------------------------------- loop [30] 100.0% [48] 200(1.5%) 200(1.5%) hash-update! ...cket/private/more-scheme.rkt:365:4 ------------------------------------------------------------------------- ??? [39] 100.0% [49] 2686(20.0%) 2102(15.6%) ival-log ...bie/findroot/src/biginterval.rkt:202:0 bflog [61] 21.7% ------------------------------------------------------------------------- for-loop [42] 100.0% [50] 1852(13.8%) 0(0.0%) taylor-alt ...rbie/findroot/src/mainloop.rkt:146:0 for-loop [58] 100.0% ------------------------------------------------------------------------- ??? [40] 100.0% [51] 1634(12.2%) 0(0.0%) for-loop ...e/findroot/src/core/alt-table.rkt:46:2 atab-add-altn [59] 61.2% errors [66] 25.1% loop [62] 13.7% ------------------------------------------------------------------------- ??? [43] 100.0% [52] 698(5.2%) 0(0.0%) egraph-run .../pkgs/egg-herbie-linux/main.rkt:49:0 egraph-add-exprs [60] 100.0% ------------------------------------------------------------------------- ??? [44] 100.0% [53] 426(3.2%) 426(3.2%) return/no-unsupplied ...ct/private/arr-i.rkt:566:0 ------------------------------------------------------------------------- for-loop [46] 100.0% [54] 424(3.2%) 0(0.0%) rewrite-expression-head24 ...core/matcher.rkt:85:0 rewriter [63] 100.0% ------------------------------------------------------------------------- ??? [39] 100.0% [55] 400(3.0%) 194(1.4%) ival-sub ...bie/findroot/src/biginterval.rkt:108:0 bfsub [82] 51.5% ------------------------------------------------------------------------- sample-multi-bounded [47] 100.0% [56] 368(2.7%) 184(1.4%) random-ranges ...bie/findroot/src/common.rkt:164:0 cons/c [64] 50.0% ------------------------------------------------------------------------- ??? [40] 100.0% [57] 200(1.5%) 0(0.0%) atab-add-altns ...root/src/core/alt-table.rkt:40:0 loop [62] 100.0% ------------------------------------------------------------------------- taylor-alt [50] 100.0% [58] 1852(13.8%) 0(0.0%) for-loop ...herbie/findroot/src/mainloop.rkt:152:6 ??? [65] 100.0% ------------------------------------------------------------------------- for-loop [51] 100.0% [59] 1000(7.4%) 0(0.0%) atab-add-altn ...root/src/core/alt-table.rkt:194:0 errors [66] 80.0% for-loop [69] 20.0% ------------------------------------------------------------------------- egraph-run [52] 100.0% [60] 698(5.2%) 0(0.0%) egraph-add-exprs ...gg-herbie-linux/main.rkt:111:0 ??? [67] 100.0% ------------------------------------------------------------------------- ival-log [49] 100.0% [61] 584(4.3%) 0(0.0%) bflog ...-lib/math/private/bigfloat/mpfr.rkt:632:4 new-mpfr [86] 100.0% ------------------------------------------------------------------------- atab-add-altns [57] 47.2% for-loop [51] 52.8% [62] 424(3.2%) 424(3.2%) loop ...rbie/findroot/src/core/alt-table.rkt:168:2 ------------------------------------------------------------------------- rewrite-expression-head24 [54] 29.5% matcher [78] 70.5% [63] 424(3.2%) 424(3.2%) rewriter ...bie/findroot/src/core/matcher.rkt:87:2 for-loop [68] 70.5% ------------------------------------------------------------------------- random-ranges [56] 100.0% [64] 184(1.4%) 0(0.0%) cons/c .../racket/contract/private/guts.rkt:500:15 coerce-contract [70] 100.0% ------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [19] 5.9% real->precision [45] 12.4% loop [9] 17.8% location-do [72] 17.8% for-loop [58] 17.8% loop [30] 28.3% [65] 3464(25.8%) 204(1.5%) ??? ...tract/private/arrow-higher-order.rkt:375:33 ival-mult [75] 28.3% location-do [72] 17.8% approximate9 [2] 17.8% hash-ref! [73] 17.8% bf [77] 12.4% ------------------------------------------------------------------------- for-loop [51] 22.3% .../more-scheme.rkt:261:28 [19] 34.1% atab-add-altn [59] 43.6% [66] 1836(13.7%) 0(0.0%) errors ...ies/herbie/findroot/src/points.rkt:190:0 for-loop [74] 56.5% temp85_0 [71] 43.5% ------------------------------------------------------------------------- egraph-add-exprs [60] 100.0% [67] 698(5.2%) 0(0.0%) ??? ...herbie/findroot/src/core/simplify.rkt:102:6 for-loop [76] 100.0% ------------------------------------------------------------------------- rewriter [63] 100.0% [68] 424(3.2%) 0(0.0%) for-loop ...bie/findroot/src/core/matcher.rkt:89:4 matcher [78] 100.0% ------------------------------------------------------------------------- atab-add-altn [59] 100.0% [69] 200(1.5%) 200(1.5%) for-loop .../findroot/src/core/alt-table.rkt:110:2 ------------------------------------------------------------------------- cons/c [64] 100.0% [70] 184(1.4%) 0(0.0%) coerce-contract ...contract/private/guts.rkt:307:0 coerce-contract/f [79] 100.0% ------------------------------------------------------------------------- errors [66] 32.6% eval-const-expr [41] 67.4% [71] 2450(18.2%) 0(0.0%) temp85_0 (unknown source) compile15 [80] 100.0% ------------------------------------------------------------------------- ??? [65] 37.6% location-do [72] 62.4% [72] 1852(13.8%) 0(0.0%) location-do ...rbie/findroot/src/programs.rkt:63:0 location-do [72] 62.4% ??? [65] 37.6% ------------------------------------------------------------------------- ??? [83] 3.6% for-loop [10] 13.5% for-loop [13] 15.8% ??? [81] 33.6% ??? [65] 33.6% [73] 1852(13.8%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 ??? [81] 33.6% ??? [3] 18.9% parse-loop74 [33] 18.6% ??? [6] 15.8% f40 [17] 9.5% ??? [83] 3.6% ------------------------------------------------------------------------- errors [66] 100.0% [74] 1038(7.7%) 222(1.7%) for-loop ...s/herbie/findroot/src/points.rkt:192:2 .../more-scheme.rkt:261:28 [19] 78.6% ------------------------------------------------------------------------- ??? [65] 100.0% [75] 980(7.3%) 586(4.4%) ival-mult ...ie/findroot/src/biginterval.rkt:120:0 bfsub [82] 40.2% ------------------------------------------------------------------------- ??? [67] 100.0% [76] 698(5.2%) 698(5.2%) for-loop ...e/findroot/src/core/simplify.rkt:121:2 ------------------------------------------------------------------------- ??? [65] 100.0% [77] 428(3.2%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:615:2 new-mpfr [86] 100.0% ------------------------------------------------------------------------- for-loop [14] 50.0% for-loop [68] 50.0% [78] 424(3.2%) 0(0.0%) matcher ...bie/findroot/src/core/matcher.rkt:115:2 ??? [7] 50.0% rewriter [63] 50.0% ------------------------------------------------------------------------- coerce-contract [70] 100.0% [79] 184(1.4%) 0(0.0%) coerce-contract/f ...ntract/private/guts.rkt:372:0 coerce-simple-value [84] 100.0% ------------------------------------------------------------------------- temp85_0 [71] 100.0% [80] 2450(18.2%) 0(0.0%) compile15 (unknown source) temp91_0 [85] 100.0% ------------------------------------------------------------------------- hash-ref! [73] 100.0% [81] 1852(13.8%) 0(0.0%) ??? ...s/herbie/findroot/src/core/taylor.rkt:44:15 hash-ref! [73] 100.0% ------------------------------------------------------------------------- ival-sub [55] 34.3% ival-mult [75] 65.7% [82] 600(4.5%) 0(0.0%) bfsub ...-lib/math/private/bigfloat/mpfr.rkt:835:4 ??? [87] 65.7% new-mpfr [86] 34.3% ------------------------------------------------------------------------- hash-ref! [73] 100.0% [83] 200(1.5%) 0(0.0%) ??? .../herbie/findroot/src/core/taylor.rkt:343:33 hash-ref! [73] 100.0% ------------------------------------------------------------------------- coerce-contract/f [79] 100.0% [84] 184(1.4%) 184(1.4%) coerce-simple-value ...ract/private/guts.rkt:385:0 ------------------------------------------------------------------------- compile15 [80] 100.0% [85] 2450(18.2%) 0(0.0%) temp91_0 (unknown source) temp118_0 [88] 100.0% ------------------------------------------------------------------------- bfsub [82] 16.9% bf [77] 35.1% bflog [61] 47.9% [86] 1218(9.1%) 1218(9.1%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:348:0 ------------------------------------------------------------------------- bfsub [82] 100.0% [87] 394(2.9%) 394(2.9%) ??? /opt/racket-7.5/collects/ffi/unsafe.rkt:938:4 ------------------------------------------------------------------------- temp91_0 [85] 100.0% [88] 2450(18.2%) 0(0.0%) temp118_0 (unknown source) expand-capturing-lifts [89] 100.0% ------------------------------------------------------------------------- loop [98] 7.5% temp118_0 [88] 92.5% [89] 2650(19.7%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [90] 84.8% expand-id-application-form17 [91] 7.6% dispatch-transformer41 [94] 7.5% ------------------------------------------------------------------------- expand-capturing-lifts [89] 100.0% [90] 2248(16.7%) 0(0.0%) lambda-clause-expander (unknown source) finish-bodys [92] 100.0% ------------------------------------------------------------------------- expand-capturing-lifts [89] 100.0% [91] 202(1.5%) 0(0.0%) expand-id-application-form17 (unknown source) syntax-e/no-taint [11] 100.0% ------------------------------------------------------------------------- lambda-clause-expander [90] 15.2% for-loop [93] 84.8% [92] 2248(16.7%) 0(0.0%) finish-bodys (unknown source) for-loop [93] 100.0% ------------------------------------------------------------------------- fallback-loop [18] 1.4% for-loop [93] 14.1% finish-bodys [92] 84.5% [93] 2248(16.7%) 596(4.4%) for-loop (unknown source) finish-bodys [92] 71.8% for-loop [93] 14.1% expand-implicit [4] 6.7% syntax-e [5] 2.1% dispatch-transformer41 [94] 1.6% add-local-binding!39 [95] 1.1% expand-body7 [96] 0.6% ------------------------------------------------------------------------- expand-capturing-lifts [89] 33.1% for-loop [93] 66.9% [94] 604(4.5%) 0(0.0%) dispatch-transformer41 (unknown source) apply-transformer52 [97] 100.0% ------------------------------------------------------------------------- for-loop [93] 100.0% [95] 220(1.6%) 220(1.6%) add-local-binding!39 (unknown source) ------------------------------------------------------------------------- for-loop [93] 100.0% [96] 198(1.5%) 198(1.5%) expand-body7 (unknown source) ------------------------------------------------------------------------- dispatch-transformer41 [94] 100.0% [97] 604(4.5%) 0(0.0%) apply-transformer52 (unknown source) loop [98] 100.0% ------------------------------------------------------------------------- eval-const-expr [41] 7.0% loop [98] 7.0% datum->syntax6 [15] 15.5% apply-transformer52 [97] 21.0% syntax-e/no-taint [11] 49.5% [98] 1436(10.7%) 222(1.7%) loop (unknown source) gf [99] 49.5% syntax-e [5] 14.1% loop [98] 7.0% expand-capturing-lifts [89] 7.0% core-form-sym [100] 7.0% ------------------------------------------------------------------------- loop [98] 100.0% [99] 1014(7.5%) 814(6.1%) gf (unknown source) propagation-apply [101] 19.7% ------------------------------------------------------------------------- loop [98] 100.0% [100] 200(1.5%) 0(0.0%) core-form-sym (unknown source) resolve+shift28 [12] 100.0% ------------------------------------------------------------------------- gf [99] 100.0% [101] 200(1.5%) 200(1.5%) propagation-apply (unknown source) -------------------------------------------------------------------------