Profiling results ----------------- Total cpu time observed: 6132ms (out of 6332ms) Number of samples taken: 30 (once every 204ms) ==================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ==================================================================== [1] 6132(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [10] 100.0% -------------------------------------------------------------------- location-do [2] 28.4% ??? [68] 71.6% [2] 1432(23.4%) 0(0.0%) location-do ...re-ival-tests/src/programs.rkt:63:0 ??? [68] 43.3% location-do [2] 28.4% loop [13] 28.4% -------------------------------------------------------------------- ??? [68] 100.0% [3] 1232(20.1%) 0(0.0%) approximate9 ...val-tests/src/core/taylor.rkt:11:0 loop [12] 66.4% for-loop [14] 33.6% -------------------------------------------------------------------- hash-ref! [19] 100.0% [4] 408(6.7%) 0(0.0%) ??? .../more-ival-tests/src/core/taylor.rkt:290:26 for-loop [15] 100.0% -------------------------------------------------------------------- taylor [75] 100.0% [5] 202(3.3%) 0(0.0%) taylor-invert ...l-tests/src/core/taylor.rkt:317:0 parse-loop74 [11] 100.0% -------------------------------------------------------------------- taylor [75] 100.0% [6] 202(3.3%) 0(0.0%) debug5 ...rbie/more-ival-tests/src/debug.rkt:102:0 ??? [50] 100.0% -------------------------------------------------------------------- map [69] 100.0% [7] 200(3.3%) 0(0.0%) f61 (unknown source) composed [16] 100.0% -------------------------------------------------------------------- free-variables [21] 100.0% [8] 200(3.3%) 0(0.0%) variable? ...val-tests/src/syntax/syntax.rkt:729:0 ??? [50] 100.0% -------------------------------------------------------------------- for-loop [96] 100.0% [9] 200(3.3%) 0(0.0%) expand-identifier (unknown source) resolve+shift28 [17] 100.0% -------------------------------------------------------------------- ??? [1] 100.0% [10] 6132(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [18] 100.0% -------------------------------------------------------------------- parse-loop74 [11] 12.7% taylor-invert [5] 24.7% map [69] 25.4% hash-ref! [19] 37.2% [11] 818(13.3%) 0(0.0%) parse-loop74 (unknown source) eval-const-expr [20] 87.3% parse-loop74 [11] 12.7% -------------------------------------------------------------------- approximate9 [3] 100.0% [12] 818(13.3%) 0(0.0%) loop ...e/more-ival-tests/src/core/taylor.rkt:86:4 hash-ref! [19] 100.0% -------------------------------------------------------------------- loop [13] 33.5% location-do [2] 66.5% [13] 812(13.2%) 0(0.0%) loop ...rbie/more-ival-tests/src/programs.rkt:72:4 ??? [68] 66.5% loop [13] 33.5% -------------------------------------------------------------------- approximate9 [3] 100.0% [14] 414(6.8%) 0(0.0%) for-loop ...e-ival-tests/src/core/taylor.rkt:29:18 taylor [75] 100.0% -------------------------------------------------------------------- ??? [4] 100.0% [15] 408(6.7%) 0(0.0%) for-loop ...-ival-tests/src/core/taylor.rkt:290:52 hash-ref! [19] 100.0% -------------------------------------------------------------------- f61 [7] 100.0% [16] 200(3.3%) 0(0.0%) composed ...ollects/racket/private/list.rkt:321:16 free-variables [21] 100.0% -------------------------------------------------------------------- expand-identifier [9] 100.0% [17] 200(3.3%) 0(0.0%) resolve+shift28 (unknown source) fallback-loop [22] 100.0% -------------------------------------------------------------------- profile-thunk16 [10] 100.0% [18] 6132(100.0%) 0(0.0%) run ...et-7.5/share/pkgs/profile-lib/main.rkt:39:2 .../more-scheme.rkt:261:28 [23]100.0% -------------------------------------------------------------------- for-loop [15] 11.2% ??? [24] 13.3% ??? [25] 19.7% ??? [68] 27.9% loop [12] 27.9% [19] 818(13.3%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 ??? [24] 41.2% ??? [25] 19.7% ??? [4] 11.2% parse-loop74 [11] 11.2% f40 [26] 8.5% taylor [75] 8.2% -------------------------------------------------------------------- parse-loop74 [11] 100.0% [20] 818(13.3%) 0(0.0%) eval-const-expr ...al-tests/src/programs.rkt:120:0 temp85_0 [81] 74.6% loop [101] 25.4% -------------------------------------------------------------------- composed [16] 25.0% append-map [27] 75.0% [21] 200(3.3%) 0(0.0%) free-variables ...ival-tests/src/programs.rkt:56:0 append-map [27] 75.0% variable? [8] 25.0% -------------------------------------------------------------------- resolve+shift28 [17] 100.0% [22] 200(3.3%) 0(0.0%) fallback-loop (unknown source) for-loop [96] 100.0% -------------------------------------------------------------------- for-loop [80] 5.0% run [18] 95.0% [23] 6132(100.0%) 200(3.3%) .../more-scheme.rkt:261:28 (unknown source) run-improve47 [28] 47.4% prepare-points-intervals [29] 29.5% errors [74] 8.3% get-final-combination [30] 3.4% ??? [104] 3.4% eval-errors [31] 3.3% oracle-error [32] 3.2% -------------------------------------------------------------------- hash-ref! [19] 100.0% [24] 818(13.3%) 0(0.0%) ??? ...e/more-ival-tests/src/core/taylor.rkt:67:15 ??? [68] 74.9% hash-ref! [19] 25.1% -------------------------------------------------------------------- hash-ref! [19] 100.0% [25] 616(10.0%) 0(0.0%) ??? ...e/more-ival-tests/src/core/taylor.rkt:44:15 hash-ref! [19] 100.0% -------------------------------------------------------------------- ??? [68] 16.3% map [69] 32.7% hash-ref! [19] 51.0% [26] 408(6.7%) 0(0.0%) f40 (unknown source) map [69] 100.0% -------------------------------------------------------------------- free-variables [21] 100.0% [27] 200(3.3%) 0(0.0%) append-map ...t-7.5/collects/racket/list.rkt:586:2 free-variables [21] 100.0% -------------------------------------------------------------------- .../more-scheme.rkt:261:28 [23]100.0% [28] 3106(50.7%) 0(0.0%) run-improve47 ...ival-tests/src/mainloop.rkt:346:0 for-loop [33] 100.0% -------------------------------------------------------------------- .../more-scheme.rkt:261:28 [23]100.0% [29] 1810(29.5%) 0(0.0%) prepare-points-intervals ...s/src/points.rkt:120:0 loop [34] 100.0% -------------------------------------------------------------------- .../more-scheme.rkt:261:28 [23]100.0% [30] 206(3.4%) 0(0.0%) get-final-combination ...ts/src/mainloop.rkt:371:0 infer-splitpoints [35] 100.0% -------------------------------------------------------------------- .../more-scheme.rkt:261:28 [23]100.0% [31] 202(3.3%) 0(0.0%) eval-errors ...ore-ival-tests/src/points.rkt:168:0 for-loop [36] 100.0% -------------------------------------------------------------------- .../more-scheme.rkt:261:28 [23]100.0% [32] 196(3.2%) 0(0.0%) oracle-error ...re-ival-tests/src/points.rkt:177:0 for-loop [37] 100.0% -------------------------------------------------------------------- run-improve47 [28] 100.0% [33] 3106(50.7%) 0(0.0%) for-loop ...more-ival-tests/src/mainloop.rkt:365:4 run-iter! [38] 100.0% -------------------------------------------------------------------- prepare-points-intervals [29] 100.0% [34] 1810(29.5%) 0(0.0%) loop ...erbie/more-ival-tests/src/points.rkt:132:4 ??? [39] 66.4% loop [41] 11.4% andmap [43] 11.2% do-curry [44] 11.0% -------------------------------------------------------------------- get-final-combination [30] 100.0% [35] 206(3.4%) 0(0.0%) infer-splitpoints ...sts/src/core/regimes.rkt:33:0 for-loop [40] 100.0% -------------------------------------------------------------------- eval-errors [31] 100.0% [36] 202(3.3%) 0(0.0%) for-loop ...e/more-ival-tests/src/points.rkt:170:2 ??? [42] 100.0% -------------------------------------------------------------------- oracle-error [32] 100.0% [37] 196(3.2%) 0(0.0%) for-loop ...e/more-ival-tests/src/points.rkt:178:2 loop [101] 100.0% -------------------------------------------------------------------- for-loop [33] 100.0% [38] 3106(50.7%) 0(0.0%) run-iter! ...ore-ival-tests/src/mainloop.rkt:327:0 gen-series! [45] 46.1% finalize-iter! [46] 33.2% simplify! [47] 14.1% choose-best-alt! [49] 6.6% -------------------------------------------------------------------- loop [34] 100.0% [39] 1202(19.6%) 400(6.5%) ??? ...herbie/more-ival-tests/src/points.rkt:113:6 loop [101] 49.9% sample-multi-bounded [102] 16.8% -------------------------------------------------------------------- infer-splitpoints [35] 100.0% [40] 206(3.4%) 0(0.0%) for-loop ...e-ival-tests/src/core/regimes.rkt:45:6 option-on-expr [48] 100.0% -------------------------------------------------------------------- loop [34] 100.0% [41] 206(3.4%) 0(0.0%) loop ...herbie/more-ival-tests/src/points.rkt:79:2 ??? [68] 100.0% -------------------------------------------------------------------- for-loop [36] 100.0% [42] 202(3.3%) 202(3.3%) ??? ...7.5/collects/racket/private/for.rkt:1236:14 -------------------------------------------------------------------- loop [34] 100.0% [43] 202(3.3%) 0(0.0%) andmap ...5/collects/racket/private/map.rkt:102:13 ??? [77] 100.0% -------------------------------------------------------------------- loop [34] 100.0% [44] 200(3.3%) 200(3.3%) do-curry ...-7.5/collects/racket/function.rkt:71:2 -------------------------------------------------------------------- run-iter! [38] 100.0% [45] 1432(23.4%) 0(0.0%) gen-series! ...e-ival-tests/src/mainloop.rkt:160:0 for-loop [51] 100.0% -------------------------------------------------------------------- run-iter! [38] 100.0% [46] 1032(16.8%) 0(0.0%) finalize-iter! ...val-tests/src/mainloop.rkt:278:0 ??? [50] 100.0% -------------------------------------------------------------------- run-iter! [38] 100.0% [47] 438(7.1%) 0(0.0%) simplify! ...ore-ival-tests/src/mainloop.rkt:224:0 ??? [52] 100.0% -------------------------------------------------------------------- for-loop [40] 100.0% [48] 206(3.4%) 0(0.0%) option-on-expr ...tests/src/core/regimes.rkt:124:0 err-lsts->split-indices [53] 100.0% -------------------------------------------------------------------- run-iter! [38] 100.0% [49] 204(3.3%) 0(0.0%) choose-best-alt! ...l-tests/src/mainloop.rkt:117:0 ...ow-val-first.rkt:486:18 [54]100.0% -------------------------------------------------------------------- variable? [8] 13.9% debug5 [6] 14.1% finalize-iter! [46] 72.0% [50] 1434(23.4%) 402(6.6%) ??? ...contract/private/arrow-val-first.rkt:486:18 for-loop [56] 72.0% -------------------------------------------------------------------- gen-series! [45] 100.0% [51] 1432(23.4%) 0(0.0%) for-loop ...more-ival-tests/src/mainloop.rkt:167:7 taylor-alt [55] 100.0% -------------------------------------------------------------------- simplify! [47] 100.0% [52] 438(7.1%) 0(0.0%) ??? .../more-ival-tests/src/core/simplify.rkt:42:0 egraph-run [57] 100.0% -------------------------------------------------------------------- option-on-expr [48] 100.0% [53] 206(3.4%) 0(0.0%) err-lsts->split-indices .../core/regimes.rkt:263:0 loop [58] 100.0% -------------------------------------------------------------------- choose-best-alt! [49] 100.0% [54] 204(3.3%) 0(0.0%) ...ow-val-first.rkt:486:18 (unknown source) ??? [59] 100.0% -------------------------------------------------------------------- for-loop [51] 100.0% [55] 1432(23.4%) 0(0.0%) taylor-alt ...re-ival-tests/src/mainloop.rkt:146:0 for-loop [60] 100.0% -------------------------------------------------------------------- ??? [50] 100.0% [56] 1032(16.8%) 0(0.0%) for-loop ...ival-tests/src/core/alt-table.rkt:46:2 atab-add-altn [62] 38.8% for-loop [63] 21.1% loop [64] 20.7% override-at-pnts [67] 19.4% -------------------------------------------------------------------- ??? [52] 100.0% [57] 438(7.1%) 0(0.0%) egraph-run .../pkgs/egg-herbie-linux/main.rkt:49:0 egraph-add-exprs [61] 100.0% -------------------------------------------------------------------- err-lsts->split-indices [53] 100.0% [58] 206(3.4%) 0(0.0%) loop ...more-ival-tests/src/core/regimes.rkt:314:4 for-loop [65] 100.0% -------------------------------------------------------------------- ...ow-val-first.rkt:486:18 [54]100.0% [59] 204(3.3%) 0(0.0%) ??? ...more-ival-tests/src/core/alt-table.rkt:49:0 argmin [66] 100.0% -------------------------------------------------------------------- taylor-alt [55] 100.0% [60] 1432(23.4%) 0(0.0%) for-loop ...more-ival-tests/src/mainloop.rkt:152:6 ??? [68] 100.0% -------------------------------------------------------------------- egraph-run [57] 100.0% [61] 438(7.1%) 0(0.0%) egraph-add-exprs ...gg-herbie-linux/main.rkt:111:0 ??? [70] 100.0% -------------------------------------------------------------------- for-loop [56] 100.0% [62] 400(6.5%) 0(0.0%) atab-add-altn ...ests/src/core/alt-table.rkt:194:0 errors [74] 100.0% -------------------------------------------------------------------- for-loop [56] 100.0% [63] 218(3.6%) 218(3.6%) for-loop ...val-tests/src/core/alt-table.rkt:110:2 -------------------------------------------------------------------- for-loop [56] 100.0% [64] 214(3.5%) 0(0.0%) loop ...re-ival-tests/src/core/alt-table.rkt:168:2 map [69] 100.0% -------------------------------------------------------------------- loop [58] 100.0% [65] 206(3.4%) 0(0.0%) for-loop ...-ival-tests/src/core/regimes.rkt:279:4 for-loop [71] 100.0% -------------------------------------------------------------------- ??? [59] 100.0% [66] 204(3.3%) 0(0.0%) argmin ...acket-7.5/collects/racket/list.rkt:765:0 ??? [72] 100.0% -------------------------------------------------------------------- for-loop [56] 100.0% [67] 200(3.3%) 0(0.0%) override-at-pnts ...s/src/core/alt-table.rkt:129:0 for-loop [73] 100.0% -------------------------------------------------------------------- loop [41] 12.6% location-do [2] 14.8% ??? [24] 16.6% loop [13] 20.6% for-loop [60] 35.4% [68] 1638(26.7%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:375:33 location-do [2] 35.4% approximate9 [3] 29.3% hash-ref! [19] 16.6% ival-mult [79] 12.6% f40 [26] 6.1% -------------------------------------------------------------------- loop [64] 20.9% taylor [75] 39.4% f40 [26] 39.8% [69] 1026(16.7%) 0(0.0%) map ...t-7.5/collects/racket/private/map.rkt:35:13 taylor [75] 39.4% loop [78] 20.9% parse-loop74 [11] 20.3% f40 [26] 13.0% f61 [7] 6.5% -------------------------------------------------------------------- egraph-add-exprs [61] 100.0% [70] 438(7.1%) 0(0.0%) ??? ...more-ival-tests/src/core/simplify.rkt:102:6 for-loop [76] 100.0% -------------------------------------------------------------------- for-loop [65] 100.0% [71] 206(3.4%) 0(0.0%) for-loop ...-ival-tests/src/core/regimes.rkt:283:8 ??? [77] 100.0% -------------------------------------------------------------------- argmin [66] 100.0% [72] 204(3.3%) 0(0.0%) ??? ...bie/more-ival-tests/src/mainloop.rkt:114:10 errors [74] 100.0% -------------------------------------------------------------------- override-at-pnts [67] 100.0% [73] 200(3.3%) 200(3.3%) for-loop ...al-tests/src/core/alt-table.rkt:130:22 -------------------------------------------------------------------- ??? [72] 16.8% atab-add-altn [62] 32.9% .../more-scheme.rkt:261:28 [23] 50.3% [74] 1216(19.8%) 0(0.0%) errors ...bie/more-ival-tests/src/points.rkt:190:0 for-loop [80] 83.6% temp85_0 [81] 16.4% -------------------------------------------------------------------- taylor [75] 8.2% hash-ref! [19] 8.2% map [69] 32.8% for-loop [14] 50.8% [75] 616(10.0%) 0(0.0%) taylor ...ore-ival-tests/src/core/taylor.rkt:169:0 debug-print [83] 34.4% map [69] 32.8% taylor-invert [5] 16.4% taylor [75] 8.2% debug5 [6] 8.2% -------------------------------------------------------------------- ??? [70] 100.0% [76] 438(7.1%) 438(7.1%) for-loop ...ival-tests/src/core/simplify.rkt:121:2 -------------------------------------------------------------------- andmap [43] 49.5% for-loop [71] 50.5% [77] 408(6.7%) 408(6.7%) ??? ...cket-7.5/collects/racket/function.rkt:93:12 -------------------------------------------------------------------- map [69] 3.4% loop [78] 96.6% [78] 214(3.5%) 0(0.0%) loop ...-7.5/collects/racket/private/map.rkt:40:19 loop [78] 96.6% ??? [82] 3.4% -------------------------------------------------------------------- ??? [68] 100.0% [79] 206(3.4%) 206(3.4%) ival-mult ...-ival-tests/src/biginterval.rkt:126:0 -------------------------------------------------------------------- errors [74] 100.0% [80] 1016(16.6%) 404(6.6%) for-loop ...e/more-ival-tests/src/points.rkt:192:2 .../more-scheme.rkt:261:28 [23] 60.2% -------------------------------------------------------------------- errors [74] 24.7% eval-const-expr [20] 75.3% [81] 810(13.2%) 202(3.3%) temp85_0 (unknown source) compile15 [84] 75.1% -------------------------------------------------------------------- loop [78] 100.0% [82] 214(3.5%) 214(3.5%) ??? ...re-ival-tests/src/core/alt-table.rkt:185:12 -------------------------------------------------------------------- taylor [75] 100.0% [83] 212(3.5%) 0(0.0%) debug-print ...more-ival-tests/src/debug.rkt:110:0 for-loop [85] 100.0% -------------------------------------------------------------------- temp85_0 [81] 100.0% [84] 608(9.9%) 0(0.0%) compile15 (unknown source) temp91_0 [86] 100.0% -------------------------------------------------------------------- debug-print [83] 100.0% [85] 212(3.5%) 212(3.5%) for-loop ...ie/more-ival-tests/src/debug.rkt:116:2 -------------------------------------------------------------------- compile15 [84] 100.0% [86] 608(9.9%) 0(0.0%) temp91_0 (unknown source) temp118_0 [87] 67.1% compile-top9 [88] 32.9% -------------------------------------------------------------------- temp91_0 [86] 100.0% [87] 408(6.7%) 0(0.0%) temp118_0 (unknown source) expand-capturing-lifts [89] 100.0% -------------------------------------------------------------------- temp91_0 [86] 100.0% [88] 200(3.3%) 0(0.0%) compile-top9 (unknown source) compile-forms33 [90] 100.0% -------------------------------------------------------------------- loop [101] 33.8% temp118_0 [87] 66.2% [89] 616(10.0%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [91] 66.2% dispatch-transformer41 [92] 33.8% -------------------------------------------------------------------- compile-top9 [88] 100.0% [90] 200(3.3%) 0(0.0%) compile-forms33 (unknown source) loop! [93] 100.0% -------------------------------------------------------------------- expand-capturing-lifts [89] 100.0% [91] 408(6.7%) 0(0.0%) lambda-clause-expander (unknown source) finish-bodys [94] 100.0% -------------------------------------------------------------------- expand-capturing-lifts [89] 100.0% [92] 208(3.4%) 0(0.0%) dispatch-transformer41 (unknown source) apply-transformer52 [95] 100.0% -------------------------------------------------------------------- compile-forms33 [90] 100.0% [93] 200(3.3%) 0(0.0%) loop! (unknown source) for-loop [96] 100.0% -------------------------------------------------------------------- lambda-clause-expander [91] 29.3% for-loop [96] 70.7% [94] 408(6.7%) 0(0.0%) finish-bodys (unknown source) for-loop [96] 100.0% -------------------------------------------------------------------- dispatch-transformer41 [92] 100.0% [95] 208(3.4%) 0(0.0%) apply-transformer52 (unknown source) apply-post-expansion [97] 100.0% -------------------------------------------------------------------- fallback-loop [22] 1.9% for-loop [96] 22.9% loop! [93] 32.9% finish-bodys [94] 42.3% [96] 608(9.9%) 200(3.3%) for-loop (unknown source) compile5 [100] 32.9% finish-bodys [94] 31.8% for-loop [96] 22.9% expand-implicit [98] 8.6% expand-identifier [9] 1.9% -------------------------------------------------------------------- apply-transformer52 [95] 100.0% [97] 208(3.4%) 0(0.0%) apply-post-expansion (unknown source) gf [99] 100.0% -------------------------------------------------------------------- for-loop [96] 100.0% [98] 208(3.4%) 208(3.4%) expand-implicit (unknown source) -------------------------------------------------------------------- apply-post-expansion [97] 100.0% [99] 208(3.4%) 0(0.0%) gf (unknown source) loop [101] 100.0% -------------------------------------------------------------------- for-loop [96] 100.0% [100] 200(3.3%) 200(3.3%) compile5 (unknown source) -------------------------------------------------------------------- gf [99] 10.4% eval-const-expr [20] 10.4% for-loop [37] 19.5% ??? [39] 59.8% [101] 1004(16.4%) 208(3.4%) loop (unknown source) sample-multi-bounded [102] 59.8% ??? [103] 19.5% expand-capturing-lifts [89] 10.4% -------------------------------------------------------------------- ??? [39] 25.2% loop [101] 74.8% [102] 802(13.1%) 0(0.0%) sample-multi-bounded ...-tests/src/points.rkt:14:0 for-loop [105] 50.1% random-ranges [106] 49.9% -------------------------------------------------------------------- loop [101] 100.0% [103] 196(3.2%) 0(0.0%) ??? ...erbie/more-ival-tests/src/points.rkt:179:26 ??? [104] 100.0% -------------------------------------------------------------------- ??? [103] 32.2% .../more-scheme.rkt:261:28 [23] 67.8% [104] 608(9.9%) 608(9.9%) ??? ...ore-ival-tests/src/syntax/syntax.rkt:214:17 -------------------------------------------------------------------- sample-multi-bounded [102] 100.0% [105] 402(6.6%) 402(6.6%) for-loop ...ie/more-ival-tests/src/points.rkt:20:4 -------------------------------------------------------------------- sample-multi-bounded [102] 100.0% [106] 400(6.5%) 200(3.3%) random-ranges ...e-ival-tests/src/common.rkt:164:0 build--> [107] 50.0% -------------------------------------------------------------------- random-ranges [106] 100.0% [107] 200(3.3%) 200(3.3%) build--> ...act/private/arrow-val-first.rkt:1214:0 --------------------------------------------------------------------