Profiling results ----------------- Total cpu time observed: 11572ms (out of 12004ms) Number of samples taken: 57 (once every 203ms) ===================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ===================================================================== [1] 11572(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [3] 100.0% --------------------------------------------------------------------- matcher [51] 100.0% [2] 422(3.6%) 0(0.0%) ??? ...aster-eval-prog/src/core/matcher.rkt:127:11 for-loop [4] 100.0% --------------------------------------------------------------------- ??? [1] 100.0% [3] 11572(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [5] 100.0% --------------------------------------------------------------------- ??? [2] 100.0% [4] 422(3.6%) 0(0.0%) for-loop ...-eval-prog/src/core/matcher.rkt:129:15 matcher [51] 100.0% --------------------------------------------------------------------- profile-thunk16 [3] 100.0% [5] 11572(100.0%) 0(0.0%) run ...et-7.5/share/pkgs/profile-lib/main.rkt:39:2 .../more-scheme.rkt:261:28 [6]100.0% --------------------------------------------------------------------- for-loop [83] 28.8% run [5] 71.2% [6] 11572(100.0%) 0(0.0%) .../more-scheme.rkt:261:28 (unknown source) run-improve47 [7] 38.8% ??? [54] 27.9% prepare-points-intervals [8] 12.2% oracle-error [9] 10.5% errors [81] 3.5% eval-errors [11] 3.5% get-final-combination [10] 2.8% ulp-difference [12] 0.9% --------------------------------------------------------------------- .../more-scheme.rkt:261:28 [6]100.0% [7] 7316(63.2%) 0(0.0%) run-improve47 ...-eval-prog/src/mainloop.rkt:346:0 for-loop [13] 100.0% --------------------------------------------------------------------- .../more-scheme.rkt:261:28 [6]100.0% [8] 1408(12.2%) 0(0.0%) prepare-points-intervals ...g/src/points.rkt:120:0 loop [14] 100.0% --------------------------------------------------------------------- .../more-scheme.rkt:261:28 [6]100.0% [9] 1210(10.5%) 0(0.0%) oracle-error ...ter-eval-prog/src/points.rkt:177:0 for-loop [15] 100.0% --------------------------------------------------------------------- .../more-scheme.rkt:261:28 [6]100.0% [10] 430(3.7%) 0(0.0%) get-final-combination ...og/src/mainloop.rkt:371:0 infer-splitpoints [16] 100.0% --------------------------------------------------------------------- .../more-scheme.rkt:261:28 [6]100.0% [11] 402(3.5%) 0(0.0%) eval-errors ...ster-eval-prog/src/points.rkt:168:0 for-loop [17] 100.0% --------------------------------------------------------------------- .../more-scheme.rkt:261:28 [6]100.0% [12] 200(1.7%) 200(1.7%) ulp-difference ...ter-eval-prog/src/float.rkt:19:0 --------------------------------------------------------------------- run-improve47 [7] 100.0% [13] 7316(63.2%) 0(0.0%) for-loop ...aster-eval-prog/src/mainloop.rkt:365:4 run-iter! [18] 100.0% --------------------------------------------------------------------- prepare-points-intervals [8] 100.0% [14] 1408(12.2%) 0(0.0%) loop ...rbie/faster-eval-prog/src/points.rkt:132:4 ??? [20] 57.2% loop [21] 42.8% --------------------------------------------------------------------- oracle-error [9] 100.0% [15] 1210(10.5%) 0(0.0%) for-loop .../faster-eval-prog/src/points.rkt:178:2 loop [19] 100.0% --------------------------------------------------------------------- get-final-combination [10] 100.0% [16] 430(3.7%) 0(0.0%) infer-splitpoints ...rog/src/core/regimes.rkt:33:0 for-loop [22] 100.0% --------------------------------------------------------------------- eval-errors [11] 100.0% [17] 402(3.5%) 0(0.0%) for-loop .../faster-eval-prog/src/points.rkt:170:2 ??? [54] 100.0% --------------------------------------------------------------------- for-loop [13] 100.0% [18] 7316(63.2%) 0(0.0%) run-iter! ...ster-eval-prog/src/mainloop.rkt:327:0 finalize-iter! [23] 82.8% gen-rewrites! [26] 8.6% simplify! [25] 8.6% --------------------------------------------------------------------- for-loop [15] 100.0% [19] 1210(10.5%) 0(0.0%) loop (unknown source) ??? [24] 66.4% ??? [54] 33.6% --------------------------------------------------------------------- loop [14] 100.0% [20] 806(7.0%) 200(1.7%) ??? ...erbie/faster-eval-prog/src/points.rkt:113:6 sample-multi-bounded [27] 75.2% --------------------------------------------------------------------- loop [14] 100.0% [21] 602(5.2%) 0(0.0%) loop ...erbie/faster-eval-prog/src/points.rkt:79:2 ??? [54] 100.0% --------------------------------------------------------------------- infer-splitpoints [16] 100.0% [22] 430(3.7%) 0(0.0%) for-loop ...er-eval-prog/src/core/regimes.rkt:45:6 option-on-expr [28] 100.0% --------------------------------------------------------------------- run-iter! [18] 100.0% [23] 6056(52.3%) 0(0.0%) finalize-iter! ...eval-prog/src/mainloop.rkt:278:0 ??? [68] 100.0% --------------------------------------------------------------------- loop [19] 100.0% [24] 804(6.9%) 0(0.0%) ??? ...rbie/faster-eval-prog/src/points.rkt:179:26 ??? [54] 100.0% --------------------------------------------------------------------- run-iter! [18] 100.0% [25] 630(5.4%) 0(0.0%) simplify! ...ster-eval-prog/src/mainloop.rkt:224:0 ??? [29] 100.0% --------------------------------------------------------------------- run-iter! [18] 100.0% [26] 630(5.4%) 0(0.0%) gen-rewrites! ...-eval-prog/src/mainloop.rkt:183:0 for-loop [31] 67.0% for-loop [33] 33.0% --------------------------------------------------------------------- ??? [20] 100.0% [27] 606(5.2%) 0(0.0%) sample-multi-bounded ...l-prog/src/points.rkt:14:0 random-ranges [30] 100.0% --------------------------------------------------------------------- for-loop [22] 100.0% [28] 430(3.7%) 0(0.0%) option-on-expr ...-prog/src/core/regimes.rkt:124:0 sort-context-on-expr [32] 52.1% for-loop [34] 47.9% --------------------------------------------------------------------- simplify! [25] 100.0% [29] 630(5.4%) 0(0.0%) ??? ...faster-eval-prog/src/core/simplify.rkt:42:0 egraph-run [35] 100.0% --------------------------------------------------------------------- sample-multi-bounded [27] 100.0% [30] 606(5.2%) 0(0.0%) random-ranges ...er-eval-prog/src/common.rkt:164:0 build--> [37] 67.0% cons/c [40] 33.0% --------------------------------------------------------------------- gen-rewrites! [26] 100.0% [31] 422(3.6%) 0(0.0%) for-loop ...ster-eval-prog/src/mainloop.rkt:191:11 rewrite-expression-head24 [36]100.0% --------------------------------------------------------------------- option-on-expr [28] 100.0% [32] 224(1.9%) 0(0.0%) sort-context-on-expr ...src/core/regimes.rkt:117:0 ??? [38] 100.0% --------------------------------------------------------------------- gen-rewrites! [26] 100.0% [33] 208(1.8%) 0(0.0%) for-loop ...aster-eval-prog/src/mainloop.rkt:208:4 for-loop [39] 100.0% --------------------------------------------------------------------- option-on-expr [28] 100.0% [34] 206(1.8%) 0(0.0%) for-loop ...r-eval-prog/src/core/regimes.rkt:135:4 errors [81] 100.0% --------------------------------------------------------------------- ??? [29] 100.0% [35] 630(5.4%) 0(0.0%) egraph-run .../pkgs/egg-herbie-linux/main.rkt:49:0 egraph-add-exprs [41] 100.0% --------------------------------------------------------------------- for-loop [31] 100.0% [36] 422(3.6%) 0(0.0%) rewrite-expression-head24 ...core/matcher.rkt:85:0 rewriter [42] 100.0% --------------------------------------------------------------------- random-ranges [30] 100.0% [37] 406(3.5%) 206(1.8%) build--> ...act/private/arrow-val-first.rkt:1214:0 coerce-contract [43] 49.3% --------------------------------------------------------------------- sort-context-on-expr [32] 100.0% [38] 224(1.9%) 0(0.0%) ??? ...-7.5/collects/racket/private/sort.rkt:369:3 generic-sort/key [44] 100.0% --------------------------------------------------------------------- for-loop [33] 100.0% [39] 208(1.8%) 0(0.0%) for-loop ...aster-eval-prog/src/mainloop.rkt:209:6 ??? [63] 100.0% --------------------------------------------------------------------- random-ranges [30] 100.0% [40] 200(1.7%) 0(0.0%) cons/c .../racket/contract/private/guts.rkt:500:15 coerce-contract [43] 100.0% --------------------------------------------------------------------- egraph-run [35] 100.0% [41] 630(5.4%) 0(0.0%) egraph-add-exprs ...gg-herbie-linux/main.rkt:111:0 ??? [45] 100.0% --------------------------------------------------------------------- rewrite-expression-head24 [36] 37.8% matcher [51] 62.2% [42] 422(3.6%) 0(0.0%) rewriter ...er-eval-prog/src/core/matcher.rkt:87:2 for-loop [46] 100.0% --------------------------------------------------------------------- cons/c [40] 50.0% build--> [37] 50.0% [43] 400(3.5%) 0(0.0%) coerce-contract ...contract/private/guts.rkt:307:0 coerce-contract/f [47] 100.0% --------------------------------------------------------------------- ??? [38] 100.0% [44] 224(1.9%) 0(0.0%) generic-sort/key .../racket/private/sort.rkt:156:2 copying-mergesort [48] 100.0% --------------------------------------------------------------------- egraph-add-exprs [41] 100.0% [45] 630(5.4%) 0(0.0%) ??? ...aster-eval-prog/src/core/simplify.rkt:102:6 for-loop [49] 100.0% --------------------------------------------------------------------- rewriter [42] 100.0% [46] 422(3.6%) 0(0.0%) for-loop ...er-eval-prog/src/core/matcher.rkt:89:4 matcher [51] 62.2% for-loop [50] 37.8% --------------------------------------------------------------------- coerce-contract [43] 100.0% [47] 400(3.5%) 0(0.0%) coerce-contract/f ...ntract/private/guts.rkt:372:0 coerce-simple-value [52] 100.0% --------------------------------------------------------------------- generic-sort/key [44] 100.0% [48] 224(1.9%) 0(0.0%) copying-mergesort ...racket/private/sort.rkt:129:8 loop [53] 100.0% --------------------------------------------------------------------- ??? [45] 100.0% [49] 630(5.4%) 630(5.4%) for-loop ...-eval-prog/src/core/simplify.rkt:121:2 --------------------------------------------------------------------- for-loop [46] 100.0% [50] 422(3.6%) 0(0.0%) for-loop ...er-eval-prog/src/core/matcher.rkt:96:8 fix-up-variables [55] 100.0% --------------------------------------------------------------------- for-loop [4] 50.0% for-loop [46] 50.0% [51] 422(3.6%) 0(0.0%) matcher ...er-eval-prog/src/core/matcher.rkt:115:2 ??? [2] 50.0% rewriter [42] 50.0% --------------------------------------------------------------------- coerce-contract/f [47] 100.0% [52] 400(3.5%) 400(3.5%) coerce-simple-value ...ract/private/guts.rkt:385:0 --------------------------------------------------------------------- copying-mergesort [48] 100.0% [53] 224(1.9%) 0(0.0%) loop ...7.5/collects/racket/private/sort.rkt:96:12 ??? [54] 100.0% --------------------------------------------------------------------- loop [53] 2.5% for-loop [17] 4.5% loop [19] 4.6% loop [21] 6.8% ??? [24] 9.0% .../more-scheme.rkt:261:28 [6] 72.6% [54] 8904(76.9%) 0(0.0%) ??? ...rbie/faster-eval-prog/src/programs.rkt:97:2 hash-ref! [56] 79.2% for-loop [57] 18.4% ??? [59] 2.3% --------------------------------------------------------------------- for-loop [50] 100.0% [55] 422(3.6%) 0(0.0%) fix-up-variables ...rog/src/core/matcher.rkt:108:2 for-loop [58] 51.2% pattern-substitute [60] 48.8% --------------------------------------------------------------------- ??? [54] 44.3% ??? [61] 55.7% [56] 7056(61.0%) 2628(22.7%) hash-ref! .../racket/private/more-scheme.rkt:376:2 ??? [61] 73.5% ??? [62] 4.8% return/no-unsupplied [70] 3.3% --------------------------------------------------------------------- ??? [54] 100.0% [57] 1642(14.2%) 1034(8.9%) for-loop ...aster-eval-prog/src/programs.rkt:100:6 ??? [63] 12.7% ??? [66] 12.2% real->precision [67] 12.2% --------------------------------------------------------------------- fix-up-variables [55] 100.0% [58] 216(1.9%) 0(0.0%) for-loop ...er-eval-prog/src/core/matcher.rkt:41:9 pattern-match [64] 100.0% --------------------------------------------------------------------- ??? [54] 100.0% [59] 206(1.8%) 206(1.8%) ??? ...tract/private/arrow-higher-order.rkt:379:33 --------------------------------------------------------------------- fix-up-variables [55] 33.3% pattern-substitute [60] 66.7% [60] 206(1.8%) 0(0.0%) pattern-substitute ...og/src/core/matcher.rkt:45:0 pattern-substitute [60] 66.7% variable? [65] 33.3% --------------------------------------------------------------------- hash-ref! [56] 100.0% [61] 6454(55.8%) 1002(8.7%) ??? ...ie/faster-eval-prog/src/programs.rkt:105:18 hash-ref! [56] 80.5% table-ref [69] 6.6% ??? [68] 4.1% --------------------------------------------------------------------- hash-ref! [56] 100.0% [62] 804(6.9%) 604(5.2%) ??? ...ster-eval-prog/src/syntax/syntax.rkt:214:17 return/no-unsupplied [70] 24.9% --------------------------------------------------------------------- for-loop [39] 50.0% for-loop [57] 50.0% [63] 416(3.6%) 208(1.8%) ??? ...tract/private/arrow-higher-order.rkt:375:33 arrow-higher-order:lnp [71] 50.0% --------------------------------------------------------------------- for-loop [58] 100.0% [64] 216(1.9%) 0(0.0%) pattern-match ...al-prog/src/core/matcher.rkt:29:0 ??? [68] 100.0% --------------------------------------------------------------------- pattern-substitute [60] 100.0% [65] 206(1.8%) 0(0.0%) variable? ...eval-prog/src/syntax/syntax.rkt:729:0 value? [72] 100.0% --------------------------------------------------------------------- for-loop [57] 100.0% [66] 200(1.7%) 200(1.7%) ??? ...ects/racket/contract/private/guts.rkt:770:8 --------------------------------------------------------------------- for-loop [57] 100.0% [67] 200(1.7%) 200(1.7%) real->precision ...val-prog/src/programs.rkt:89:11 --------------------------------------------------------------------- pattern-match [64] 3.1% ??? [61] 10.3% finalize-iter! [23] 86.6% [68] 6878(59.4%) 1024(8.8%) ??? ...contract/private/arrow-val-first.rkt:486:18 for-loop [73] 83.6% atab-add-altns [76] 3.0% --------------------------------------------------------------------- ??? [61] 100.0% [69] 1212(10.5%) 1010(8.7%) table-ref .../faster-eval-prog/src/common.rkt:33:0 assoc-iterate-first [77] 16.7% --------------------------------------------------------------------- ??? [62] 24.9% hash-ref! [56] 75.1% [70] 802(6.9%) 402(3.5%) return/no-unsupplied ...ct/private/arr-i.rkt:566:0 filter [74] 49.9% --------------------------------------------------------------------- ??? [63] 100.0% [71] 208(1.8%) 0(0.0%) arrow-higher-order:lnp ...w-higher-order.rkt:673:7 ??? [75] 100.0% --------------------------------------------------------------------- variable? [65] 100.0% [72] 206(1.8%) 206(1.8%) value? ...ster-eval-prog/src/syntax/types.rkt:18:0 --------------------------------------------------------------------- ??? [68] 100.0% [73] 5848(50.5%) 0(0.0%) for-loop ...-eval-prog/src/core/alt-table.rkt:46:2 atab-add-altn [78] 68.8% errors [81] 24.3% for-loop [79] 3.5% paired-fold [80] 3.4% --------------------------------------------------------------------- return/no-unsupplied [70] 100.0% [74] 400(3.5%) 400(3.5%) filter ...5/collects/racket/private/list.rkt:256:2 --------------------------------------------------------------------- arrow-higher-order:lnp [71] 100.0% [75] 208(1.8%) 208(1.8%) ??? ...et/contract/private/arity-checking.rkt:19:2 --------------------------------------------------------------------- ??? [68] 100.0% [76] 208(1.8%) 0(0.0%) atab-add-altns ...prog/src/core/alt-table.rkt:40:0 atab-add-altn [78] 100.0% --------------------------------------------------------------------- table-ref [69] 100.0% [77] 202(1.7%) 202(1.7%) assoc-iterate-first ...cket/private/dict.rkt:166:0 --------------------------------------------------------------------- atab-add-altns [76] 4.9% for-loop [73] 95.1% [78] 4232(36.6%) 0(0.0%) atab-add-altn ...prog/src/core/alt-table.rkt:194:0 errors [81] 100.0% --------------------------------------------------------------------- for-loop [73] 100.0% [79] 202(1.7%) 0(0.0%) for-loop ...eval-prog/src/core/alt-table.rkt:110:2 ??? [82] 100.0% --------------------------------------------------------------------- for-loop [73] 100.0% [80] 200(1.7%) 200(1.7%) paired-fold ...llects/racket/private/hash.rkt:19:2 --------------------------------------------------------------------- for-loop [34] 3.1% .../more-scheme.rkt:261:28 [6] 12.1% for-loop [73] 21.3% atab-add-altn [78] 63.5% [81] 6666(57.6%) 0(0.0%) errors ...ie/faster-eval-prog/src/points.rkt:190:0 for-loop [83] 100.0% --------------------------------------------------------------------- for-loop [79] 100.0% [82] 202(1.7%) 202(1.7%) ??? ...7.5/collects/racket/private/for.rkt:1236:14 --------------------------------------------------------------------- errors [81] 100.0% [83] 6666(57.6%) 0(0.0%) for-loop .../faster-eval-prog/src/points.rkt:192:2 .../more-scheme.rkt:261:28 [6]100.0% ---------------------------------------------------------------------