Profiling results ----------------- Total cpu time observed: 11772ms (out of 12184ms) Number of samples taken: 58 (once every 203ms) ========================================================================= Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ========================================================================= [1] 11772(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [5] 100.0% ------------------------------------------------------------------------- ??? [75] 100.0% [2] 218(1.9%) 0(0.0%) approximate9 ...eval-prog/src/core/taylor.rkt:11:0 loop [6] 100.0% ------------------------------------------------------------------------- matcher [93] 100.0% [3] 200(1.7%) 0(0.0%) ??? ...aster-eval-prog/src/core/matcher.rkt:127:11 for-loop [7] 100.0% ------------------------------------------------------------------------- copying-mergesort [81] 100.0% [4] 200(1.7%) 0(0.0%) jloop ...5/collects/racket/private/sort.rkt:120:23 ??? [34] 100.0% ------------------------------------------------------------------------- ??? [1] 100.0% [5] 11772(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [8] 100.0% ------------------------------------------------------------------------- approximate9 [2] 100.0% [6] 218(1.9%) 0(0.0%) loop .../faster-eval-prog/src/core/taylor.rkt:86:4 hash-ref! [45] 100.0% ------------------------------------------------------------------------- ??? [3] 100.0% [7] 200(1.7%) 0(0.0%) for-loop ...-eval-prog/src/core/matcher.rkt:129:15 matcher [93] 100.0% ------------------------------------------------------------------------- profile-thunk16 [5] 100.0% [8] 11772(100.0%) 0(0.0%) run ...et-7.5/share/pkgs/profile-lib/main.rkt:39:2 .../more-scheme.rkt:261:28 [9] 100.0% ------------------------------------------------------------------------- for-loop [83] 25.7% run [8] 74.3% [9] 11772(100.0%) 200(1.7%) .../more-scheme.rkt:261:28 (unknown source) run-improve47 [10] 41.9% ??? [34] 23.9% prepare-points-intervals [11] 12.0% oracle-error [12] 10.3% errors [71] 4.2% eval-errors [14] 3.4% get-final-combination [13] 2.6% ulp-difference [15] 0.9% ------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [9] 100.0% [10] 7356(62.5%) 0(0.0%) run-improve47 ...-eval-prog/src/mainloop.rkt:346:0 for-loop [16] 100.0% ------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [9] 100.0% [11] 1408(12.0%) 0(0.0%) prepare-points-intervals ...g/src/points.rkt:120:0 loop [17] 100.0% ------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [9] 100.0% [12] 1208(10.3%) 0(0.0%) oracle-error ...ter-eval-prog/src/points.rkt:177:0 for-loop [18] 100.0% ------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [9] 100.0% [13] 406(3.4%) 0(0.0%) get-final-combination ...og/src/mainloop.rkt:371:0 infer-splitpoints [19] 100.0% ------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [9] 100.0% [14] 400(3.4%) 0(0.0%) eval-errors ...ster-eval-prog/src/points.rkt:168:0 for-loop [20] 100.0% ------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [9] 100.0% [15] 206(1.7%) 206(1.7%) ulp-difference ...ter-eval-prog/src/float.rkt:19:0 ------------------------------------------------------------------------- run-improve47 [10] 100.0% [16] 7356(62.5%) 0(0.0%) for-loop ...aster-eval-prog/src/mainloop.rkt:365:4 run-iter! [21] 100.0% ------------------------------------------------------------------------- prepare-points-intervals [11] 100.0% [17] 1408(12.0%) 0(0.0%) loop ...rbie/faster-eval-prog/src/points.rkt:132:4 loop [22] 71.6% ??? [25] 28.4% ------------------------------------------------------------------------- oracle-error [12] 100.0% [18] 1208(10.3%) 200(1.7%) for-loop .../faster-eval-prog/src/points.rkt:178:2 loop [23] 66.9% map [73] 16.6% ------------------------------------------------------------------------- get-final-combination [13] 100.0% [19] 406(3.4%) 0(0.0%) infer-splitpoints ...rog/src/core/regimes.rkt:33:0 for-loop [24] 100.0% ------------------------------------------------------------------------- eval-errors [14] 100.0% [20] 400(3.4%) 0(0.0%) for-loop .../faster-eval-prog/src/points.rkt:170:2 ??? [34] 100.0% ------------------------------------------------------------------------- for-loop [16] 100.0% [21] 7356(62.5%) 0(0.0%) run-iter! ...ster-eval-prog/src/mainloop.rkt:327:0 finalize-iter! [26] 82.7% simplify! [28] 6.1% gen-rewrites! [30] 5.5% gen-series! [32] 3.0% localize! [33] 2.7% ------------------------------------------------------------------------- loop [17] 100.0% [22] 1008(8.6%) 0(0.0%) loop ...erbie/faster-eval-prog/src/points.rkt:79:2 ??? [34] 100.0% ------------------------------------------------------------------------- for-loop [18] 100.0% [23] 808(6.9%) 0(0.0%) loop (unknown source) ??? [27] 100.0% ------------------------------------------------------------------------- infer-splitpoints [19] 100.0% [24] 406(3.4%) 0(0.0%) for-loop ...er-eval-prog/src/core/regimes.rkt:45:6 option-on-expr [29] 100.0% ------------------------------------------------------------------------- loop [17] 100.0% [25] 400(3.4%) 0(0.0%) ??? ...erbie/faster-eval-prog/src/points.rkt:113:6 sample-multi-bounded [31] 100.0% ------------------------------------------------------------------------- run-iter! [21] 100.0% [26] 6082(51.7%) 0(0.0%) finalize-iter! ...eval-prog/src/mainloop.rkt:278:0 ??? [35] 100.0% ------------------------------------------------------------------------- map [73] 19.8% loop [23] 80.2% [27] 1008(8.6%) 0(0.0%) ??? ...rbie/faster-eval-prog/src/points.rkt:179:26 ??? [34] 100.0% ------------------------------------------------------------------------- run-iter! [21] 100.0% [28] 450(3.8%) 0(0.0%) simplify! ...ster-eval-prog/src/mainloop.rkt:224:0 ??? [36] 100.0% ------------------------------------------------------------------------- for-loop [24] 100.0% [29] 406(3.4%) 0(0.0%) option-on-expr ...-prog/src/core/regimes.rkt:124:0 for-loop [39] 50.7% sort-context-on-expr [42] 49.3% ------------------------------------------------------------------------- run-iter! [21] 100.0% [30] 406(3.4%) 0(0.0%) gen-rewrites! ...-eval-prog/src/mainloop.rkt:183:0 for-loop [38] 50.7% for-loop [44] 49.3% ------------------------------------------------------------------------- ??? [25] 100.0% [31] 400(3.4%) 0(0.0%) sample-multi-bounded ...l-prog/src/points.rkt:14:0 random-ranges [41] 50.0% for-loop [40] 50.0% ------------------------------------------------------------------------- run-iter! [21] 100.0% [32] 218(1.9%) 0(0.0%) gen-series! ...er-eval-prog/src/mainloop.rkt:160:0 for-loop [37] 100.0% ------------------------------------------------------------------------- run-iter! [21] 100.0% [33] 200(1.7%) 0(0.0%) localize! ...ster-eval-prog/src/mainloop.rkt:127:0 localize-error [43] 100.0% ------------------------------------------------------------------------- jloop [4] 2.4% for-loop [20] 4.8% loop [22] 12.2% ??? [27] 12.2% .../more-scheme.rkt:261:28 [9] 68.3% [34] 8252(70.1%) 1202(10.2%) ??? ...rbie/faster-eval-prog/src/programs.rkt:97:2 hash-ref! [45] 75.7% for-loop [47] 9.7% ------------------------------------------------------------------------- ??? [55] 3.2% finalize-iter! [26] 96.8% [35] 6282(53.4%) 200(1.7%) ??? ...contract/private/arrow-val-first.rkt:486:18 for-loop [46] 90.0% atab-add-altns [49] 6.8% ------------------------------------------------------------------------- simplify! [28] 100.0% [36] 450(3.8%) 0(0.0%) ??? ...faster-eval-prog/src/core/simplify.rkt:42:0 egraph-run [48] 100.0% ------------------------------------------------------------------------- gen-series! [32] 100.0% [37] 218(1.9%) 0(0.0%) for-loop ...aster-eval-prog/src/mainloop.rkt:167:7 taylor-alt [50] 100.0% ------------------------------------------------------------------------- gen-rewrites! [30] 100.0% [38] 206(1.7%) 0(0.0%) for-loop ...aster-eval-prog/src/mainloop.rkt:208:4 for-loop [51] 100.0% ------------------------------------------------------------------------- option-on-expr [29] 100.0% [39] 206(1.7%) 0(0.0%) for-loop ...r-eval-prog/src/core/regimes.rkt:135:4 errors [71] 100.0% ------------------------------------------------------------------------- sample-multi-bounded [31] 100.0% [40] 200(1.7%) 200(1.7%) for-loop ...e/faster-eval-prog/src/points.rkt:20:4 ------------------------------------------------------------------------- sample-multi-bounded [31] 100.0% [41] 200(1.7%) 0(0.0%) random-ranges ...er-eval-prog/src/common.rkt:164:0 cons/c [52] 100.0% ------------------------------------------------------------------------- option-on-expr [29] 100.0% [42] 200(1.7%) 0(0.0%) sort-context-on-expr ...src/core/regimes.rkt:117:0 ??? [53] 100.0% ------------------------------------------------------------------------- localize! [33] 100.0% [43] 200(1.7%) 0(0.0%) localize-error ...-prog/src/core/localize.rkt:58:0 hash-ref! [45] 100.0% ------------------------------------------------------------------------- gen-rewrites! [30] 100.0% [44] 200(1.7%) 0(0.0%) for-loop ...ster-eval-prog/src/mainloop.rkt:191:11 rewrite-expression-head24 [54] 100.0% ------------------------------------------------------------------------- ??? [75] 1.6% loop [6] 1.6% localize-error [43] 3.0% ??? [34] 39.9% ??? [55] 53.8% [45] 6668(56.6%) 2832(24.1%) hash-ref! .../racket/private/more-scheme.rkt:376:2 ??? [55] 67.9% ??? [60] 6.1% ??? [57] 3.0% parse-loop15 [69] 3.0% ??? [63] 1.6% taylor [62] 1.6% return/no-unsupplied [61] 1.0% ------------------------------------------------------------------------- ??? [35] 100.0% [46] 5656(48.0%) 0(0.0%) for-loop ...-eval-prog/src/core/alt-table.rkt:46:2 atab-add-altn [56] 71.3% errors [71] 28.7% ------------------------------------------------------------------------- ??? [34] 100.0% [47] 800(6.8%) 200(1.7%) for-loop ...aster-eval-prog/src/programs.rkt:100:6 ??? [66] 25.0% return/no-unsupplied [61] 25.0% ??? [75] 25.0% ------------------------------------------------------------------------- ??? [36] 100.0% [48] 450(3.8%) 0(0.0%) egraph-run .../pkgs/egg-herbie-linux/main.rkt:49:0 egraph-add-exprs [58] 100.0% ------------------------------------------------------------------------- ??? [35] 100.0% [49] 426(3.6%) 0(0.0%) atab-add-altns ...prog/src/core/alt-table.rkt:40:0 loop [59] 100.0% ------------------------------------------------------------------------- for-loop [37] 100.0% [50] 218(1.9%) 0(0.0%) taylor-alt ...ter-eval-prog/src/mainloop.rkt:146:0 for-loop [64] 100.0% ------------------------------------------------------------------------- for-loop [38] 100.0% [51] 206(1.7%) 0(0.0%) for-loop ...aster-eval-prog/src/mainloop.rkt:209:6 change-apply [65] 100.0% ------------------------------------------------------------------------- random-ranges [41] 100.0% [52] 200(1.7%) 0(0.0%) cons/c .../racket/contract/private/guts.rkt:500:15 coerce-contract [67] 100.0% ------------------------------------------------------------------------- sort-context-on-expr [42] 100.0% [53] 200(1.7%) 0(0.0%) ??? ...-7.5/collects/racket/private/sort.rkt:369:3 generic-sort/key [68] 100.0% ------------------------------------------------------------------------- for-loop [44] 100.0% [54] 200(1.7%) 0(0.0%) rewrite-expression-head24 ...core/matcher.rkt:85:0 rewriter [70] 100.0% ------------------------------------------------------------------------- hash-ref! [45] 100.0% [55] 5642(47.9%) 812(6.9%) ??? ...ie/faster-eval-prog/src/programs.rkt:105:18 hash-ref! [45] 83.4% table-ref [72] 8.8% variable? [79] 0.7% ??? [35] 0.7% ------------------------------------------------------------------------- for-loop [46] 100.0% [56] 4032(34.3%) 0(0.0%) atab-add-altn ...prog/src/core/alt-table.rkt:194:0 errors [71] 94.9% loop [78] 5.1% ------------------------------------------------------------------------- hash-ref! [45] 100.0% [57] 604(5.1%) 604(5.1%) ??? ...ster-eval-prog/src/syntax/syntax.rkt:214:17 ------------------------------------------------------------------------- egraph-run [48] 100.0% [58] 450(3.8%) 0(0.0%) egraph-add-exprs ...gg-herbie-linux/main.rkt:111:0 ??? [74] 100.0% ------------------------------------------------------------------------- atab-add-altns [49] 100.0% [59] 426(3.6%) 218(1.9%) loop ...ter-eval-prog/src/core/alt-table.rkt:168:2 map [73] 48.8% ------------------------------------------------------------------------- hash-ref! [45] 100.0% [60] 408(3.5%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:379:33 ival-div [76] 100.0% ------------------------------------------------------------------------- for-loop [47] 50.0% hash-ref! [45] 50.0% [61] 400(3.4%) 400(3.4%) return/no-unsupplied ...ct/private/arr-i.rkt:566:0 ------------------------------------------------------------------------- taylor [62] 50.0% hash-ref! [45] 50.0% [62] 218(1.9%) 218(1.9%) taylor ...ster-eval-prog/src/core/taylor.rkt:169:0 taylor [62] 50.0% ------------------------------------------------------------------------- hash-ref! [45] 100.0% [63] 218(1.9%) 0(0.0%) ??? .../faster-eval-prog/src/core/taylor.rkt:67:15 ??? [75] 100.0% ------------------------------------------------------------------------- taylor-alt [50] 100.0% [64] 218(1.9%) 0(0.0%) for-loop ...aster-eval-prog/src/mainloop.rkt:152:6 ??? [75] 100.0% ------------------------------------------------------------------------- for-loop [51] 100.0% [65] 206(1.7%) 0(0.0%) change-apply ...val-prog/src/core/matcher.rkt:69:0 pattern-substitute [77] 100.0% ------------------------------------------------------------------------- for-loop [47] 100.0% [66] 200(1.7%) 0(0.0%) ??? ...ects/racket/contract/private/guts.rkt:770:8 dict? [87] 100.0% ------------------------------------------------------------------------- cons/c [52] 100.0% [67] 200(1.7%) 0(0.0%) coerce-contract ...contract/private/guts.rkt:307:0 coerce-contract/f [80] 100.0% ------------------------------------------------------------------------- ??? [53] 100.0% [68] 200(1.7%) 0(0.0%) generic-sort/key .../racket/private/sort.rkt:156:2 copying-mergesort [81] 100.0% ------------------------------------------------------------------------- hash-ref! [45] 100.0% [69] 200(1.7%) 0(0.0%) parse-loop15 (unknown source) map [73] 100.0% ------------------------------------------------------------------------- matcher [93] 50.0% rewrite-expression-head24 [54] 50.0% [70] 200(1.7%) 0(0.0%) rewriter ...er-eval-prog/src/core/matcher.rkt:87:2 for-loop [82] 100.0% ------------------------------------------------------------------------- for-loop [39] 3.1% .../more-scheme.rkt:261:28 [9] 14.9% for-loop [46] 24.4% atab-add-altn [56] 57.5% [71] 6652(56.5%) 0(0.0%) errors ...ie/faster-eval-prog/src/points.rkt:190:0 for-loop [83] 100.0% ------------------------------------------------------------------------- ??? [55] 100.0% [72] 992(8.4%) 600(5.1%) table-ref .../faster-eval-prog/src/common.rkt:33:0 for-loop [90] 20.2% dict? [87] 19.4% ------------------------------------------------------------------------- parse-loop15 [69] 32.9% for-loop [18] 32.9% loop [59] 34.2% [73] 608(5.2%) 0(0.0%) map ...t-7.5/collects/racket/private/map.rkt:35:13 loop [85] 67.1% ??? [27] 32.9% ------------------------------------------------------------------------- egraph-add-exprs [58] 100.0% [74] 450(3.8%) 0(0.0%) ??? ...aster-eval-prog/src/core/simplify.rkt:102:6 for-loop [84] 100.0% ------------------------------------------------------------------------- ??? [63] 17.4% location-do [88] 17.4% for-loop [64] 17.4% for-loop [47] 47.8% [75] 418(3.6%) 200(1.7%) ??? ...tract/private/arrow-higher-order.rkt:375:33 location-do [88] 17.4% approximate9 [2] 17.4% hash-ref! [45] 17.4% ------------------------------------------------------------------------- ??? [60] 100.0% [76] 408(3.5%) 0(0.0%) ival-div ...er-eval-prog/src/biginterval.rkt:156:0 bfdiv [86] 100.0% ------------------------------------------------------------------------- pattern-substitute [77] 50.0% change-apply [65] 50.0% [77] 206(1.7%) 206(1.7%) pattern-substitute ...og/src/core/matcher.rkt:45:0 pattern-substitute [77] 50.0% ------------------------------------------------------------------------- atab-add-altn [56] 100.0% [78] 204(1.7%) 204(1.7%) loop ...-7.5/collects/racket/private/hash.rkt:20:4 ------------------------------------------------------------------------- ??? [55] 100.0% [79] 202(1.7%) 0(0.0%) variable? ...eval-prog/src/syntax/syntax.rkt:729:0 value? [89] 100.0% ------------------------------------------------------------------------- coerce-contract [67] 100.0% [80] 200(1.7%) 0(0.0%) coerce-contract/f ...ntract/private/guts.rkt:372:0 coerce-simple-value [91] 100.0% ------------------------------------------------------------------------- generic-sort/key [68] 33.3% copying-mergesort [81] 66.7% [81] 200(1.7%) 0(0.0%) copying-mergesort ...racket/private/sort.rkt:129:8 copying-mergesort [81] 66.7% jloop [4] 33.3% ------------------------------------------------------------------------- rewriter [70] 100.0% [82] 200(1.7%) 0(0.0%) for-loop ...er-eval-prog/src/core/matcher.rkt:89:4 matcher [93] 50.0% for-loop [92] 50.0% ------------------------------------------------------------------------- errors [71] 100.0% [83] 6652(56.5%) 404(3.4%) for-loop .../faster-eval-prog/src/points.rkt:192:2 .../more-scheme.rkt:261:28 [9] 90.8% ??? [96] 3.1% ------------------------------------------------------------------------- ??? [74] 100.0% [84] 450(3.8%) 450(3.8%) for-loop ...-eval-prog/src/core/simplify.rkt:121:2 ------------------------------------------------------------------------- map [73] 7.5% loop [85] 92.5% [85] 408(3.5%) 0(0.0%) loop ...-7.5/collects/racket/private/map.rkt:40:19 loop [85] 92.5% ??? [95] 5.7% ??? [97] 1.9% ------------------------------------------------------------------------- ival-div [76] 100.0% [86] 408(3.5%) 0(0.0%) bfdiv ...-lib/math/private/bigfloat/mpfr.rkt:835:4 ??? [94] 100.0% ------------------------------------------------------------------------- table-ref [72] 49.0% ??? [66] 51.0% [87] 392(3.3%) 392(3.3%) dict? ...ollects/racket/private/generic.rkt:146:11 ------------------------------------------------------------------------- ??? [75] 33.3% location-do [88] 66.7% [88] 218(1.9%) 0(0.0%) location-do ...ter-eval-prog/src/programs.rkt:62:0 location-do [88] 66.7% ??? [75] 33.3% ------------------------------------------------------------------------- variable? [79] 100.0% [89] 202(1.7%) 202(1.7%) value? ...ster-eval-prog/src/syntax/types.rkt:18:0 ------------------------------------------------------------------------- table-ref [72] 100.0% [90] 200(1.7%) 200(1.7%) for-loop ...e/faster-eval-prog/src/common.rkt:35:4 ------------------------------------------------------------------------- coerce-contract/f [80] 100.0% [91] 200(1.7%) 200(1.7%) coerce-simple-value ...ract/private/guts.rkt:385:0 ------------------------------------------------------------------------- for-loop [82] 100.0% [92] 200(1.7%) 0(0.0%) for-loop ...er-eval-prog/src/core/matcher.rkt:96:8 fix-up-variables [98] 100.0% ------------------------------------------------------------------------- for-loop [7] 50.0% for-loop [82] 50.0% [93] 200(1.7%) 0(0.0%) matcher ...er-eval-prog/src/core/matcher.rkt:115:2 ??? [3] 50.0% rewriter [70] 50.0% ------------------------------------------------------------------------- bfdiv [86] 100.0% [94] 408(3.5%) 408(3.5%) ??? /opt/racket-7.5/collects/ffi/unsafe.rkt:938:4 ------------------------------------------------------------------------- loop [85] 100.0% [95] 208(1.8%) 208(1.8%) ??? ...ter-eval-prog/src/core/alt-table.rkt:185:12 ------------------------------------------------------------------------- for-loop [83] 100.0% [96] 206(1.7%) 206(1.7%) ??? ...7.5/collects/racket/private/for.rkt:1236:14 ------------------------------------------------------------------------- loop [85] 100.0% [97] 200(1.7%) 200(1.7%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 ------------------------------------------------------------------------- for-loop [92] 100.0% [98] 200(1.7%) 0(0.0%) fix-up-variables ...rog/src/core/matcher.rkt:108:2 for-loop [99] 100.0% ------------------------------------------------------------------------- fix-up-variables [98] 100.0% [99] 200(1.7%) 0(0.0%) for-loop ...er-eval-prog/src/core/matcher.rkt:41:9 for-loop [100] 100.0% ------------------------------------------------------------------------- for-loop [99] 100.0% [100] 200(1.7%) 0(0.0%) for-loop ...er-eval-prog/src/core/matcher.rkt:26:9 core457 [101] 100.0% ------------------------------------------------------------------------- for-loop [100] 100.0% [101] 200(1.7%) 0(0.0%) core457 ...acket/contract/private/arr-i.rkt:976:19 un-dep/maybe-chaperone [102] 100.0% ------------------------------------------------------------------------- core457 [101] 100.0% [102] 200(1.7%) 0(0.0%) un-dep/maybe-chaperone ...private/arr-i.rkt:1137:2 ??? [103] 100.0% ------------------------------------------------------------------------- un-dep/maybe-chaperone [102] 100.0% [103] 200(1.7%) 0(0.0%) ??? ...cts/racket/contract/private/guts.rkt:835:10 ??? [104] 100.0% ------------------------------------------------------------------------- ??? [103] 100.0% [104] 200(1.7%) 0(0.0%) ??? ...ntract/private/arrow-higher-order.rkt:575:2 build-subcontract-late-negs [105]100.0% ------------------------------------------------------------------------- ??? [104] 100.0% [105] 200(1.7%) 200(1.7%) build-subcontract-late-negs ...her-order.rkt:711:0 -------------------------------------------------------------------------