Profiling results ----------------- Total cpu time observed: 24100ms (out of 24220ms) Number of samples taken: 115 (once every 210ms) =========================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee =========================================================================== [1] 24100(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [3] 100.0% --------------------------------------------------------------------------- for-loop [90] 100.0% [2] 216(0.9%) 0(0.0%) syntax-e (unknown source) syntax-e/no-taint [4] 100.0% --------------------------------------------------------------------------- ??? [1] 100.0% [3] 24100(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [5] 100.0% --------------------------------------------------------------------------- syntax-e [2] 100.0% [4] 216(0.9%) 0(0.0%) syntax-e/no-taint (unknown source) loop [55] 100.0% --------------------------------------------------------------------------- profile-thunk16 [3] 100.0% [5] 24100(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 [65] 1.7% run [5] 98.3% [6] 24100(100.0%) 0(0.0%) .../more-scheme.rkt:261:28 (unknown source) prepare-points-intervals [10] 83.4% run-improve47 [7] 11.4% oracle-error [8] 1.7% ??? [25] 1.7% loop [55] 0.9% get-final-combination [9] 0.8% --------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [6] 100.0% [7] 3162(13.1%) 0(0.0%) run-improve47 ...gg-cleanup/src/mainloop.rkt:346:0 for-loop [11] 72.9% prepare-points-intervals [10] 13.1% simplify! [21] 7.1% ??? [26] 6.8% --------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [6] 100.0% [8] 416(1.7%) 0(0.0%) oracle-error ...e/egg-cleanup/src/points.rkt:188:0 for-loop [12] 100.0% --------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [6] 100.0% [9] 200(0.8%) 0(0.0%) get-final-combination ...up/src/mainloop.rkt:371:0 infer-splitpoints [13] 100.0% --------------------------------------------------------------------------- run-improve47 [7] 2.0% .../more-scheme.rkt:261:28 [6] 98.0% [10] 20512(85.1%) 0(0.0%) prepare-points-intervals ...p/src/points.rkt:110:0 loop [14] 100.0% --------------------------------------------------------------------------- run-improve47 [7] 100.0% [11] 2306(9.6%) 0(0.0%) for-loop ...bie/egg-cleanup/src/mainloop.rkt:365:4 run-iter! [15] 100.0% --------------------------------------------------------------------------- oracle-error [8] 100.0% [12] 416(1.7%) 0(0.0%) for-loop ...erbie/egg-cleanup/src/points.rkt:189:2 map [16] 100.0% --------------------------------------------------------------------------- get-final-combination [9] 100.0% [13] 200(0.8%) 0(0.0%) infer-splitpoints ...nup/src/core/regimes.rkt:34:0 debug-print [17] 100.0% --------------------------------------------------------------------------- prepare-points-intervals [10] 100.0% [14] 20512(85.1%) 400(1.7%) loop ...es/herbie/egg-cleanup/src/points.rkt:123:4 loop [18] 90.0% ??? [19] 8.0% --------------------------------------------------------------------------- for-loop [11] 100.0% [15] 2306(9.6%) 0(0.0%) run-iter! ...ie/egg-cleanup/src/mainloop.rkt:327:0 finalize-iter! [20] 54.0% simplify! [21] 37.3% gen-rewrites! [24] 8.7% --------------------------------------------------------------------------- for-loop [12] 100.0% [16] 416(1.7%) 0(0.0%) map ...t-7.5/collects/racket/private/map.rkt:35:13 ??? [22] 100.0% --------------------------------------------------------------------------- infer-splitpoints [13] 100.0% [17] 200(0.8%) 0(0.0%) debug-print ...bie/egg-cleanup/src/debug.rkt:110:0 for-loop [23] 100.0% --------------------------------------------------------------------------- loop [14] 100.0% [18] 18464(76.6%) 1656(6.9%) loop ...ies/herbie/egg-cleanup/src/points.rkt:81:2 ??? [25] 86.6% hash-update! [28] 2.3% ??? [26] 2.2% --------------------------------------------------------------------------- loop [14] 100.0% [19] 1648(6.8%) 224(0.9%) ??? ...ies/herbie/egg-cleanup/src/points.rkt:106:2 ??? [31] 86.4% --------------------------------------------------------------------------- run-iter! [15] 100.0% [20] 1246(5.2%) 0(0.0%) finalize-iter! ...g-cleanup/src/mainloop.rkt:278:0 ??? [26] 100.0% --------------------------------------------------------------------------- run-improve47 [7] 20.8% run-iter! [15] 79.2% [21] 1086(4.5%) 0(0.0%) simplify! ...ie/egg-cleanup/src/mainloop.rkt:224:0 ??? [27] 100.0% --------------------------------------------------------------------------- map [16] 100.0% [22] 416(1.7%) 208(0.9%) ??? ...es/herbie/egg-cleanup/src/points.rkt:190:26 ??? [25] 50.0% --------------------------------------------------------------------------- debug-print [17] 100.0% [23] 200(0.8%) 200(0.8%) for-loop ...herbie/egg-cleanup/src/debug.rkt:116:2 --------------------------------------------------------------------------- run-iter! [15] 100.0% [24] 200(0.8%) 0(0.0%) gen-rewrites! ...gg-cleanup/src/mainloop.rkt:183:0 for-loop [29] 100.0% --------------------------------------------------------------------------- ??? [22] 1.2% .../more-scheme.rkt:261:28 [6] 4.8% loop [18] 93.9% [25] 17018(70.6%) 3510(14.6%) ??? ...s/herbie/egg-cleanup/src/programs.rkt:115:2 ??? [30] 48.7% ??? [31] 27.0% ??? [34] 3.6% --------------------------------------------------------------------------- run-improve47 [7] 11.6% loop [18] 21.5% finalize-iter! [20] 66.9% [26] 1862(7.7%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:486:18 for-loop [32] 66.9% ??? [35] 21.5% make-alt-table [36] 11.6% --------------------------------------------------------------------------- simplify! [21] 100.0% [27] 1086(4.5%) 0(0.0%) ??? ...rbie/egg-cleanup/src/core/simplify.rkt:35:0 egraph-run [33] 100.0% --------------------------------------------------------------------------- loop [18] 100.0% [28] 420(1.7%) 420(1.7%) hash-update! ...cket/private/more-scheme.rkt:365:4 --------------------------------------------------------------------------- gen-rewrites! [24] 100.0% [29] 200(0.8%) 0(0.0%) for-loop ...ie/egg-cleanup/src/mainloop.rkt:191:11 rewrite-expression-head20 [37] 100.0% --------------------------------------------------------------------------- ??? [25] 100.0% [30] 8296(34.4%) 2518(10.4%) ??? ...tract/private/arrow-higher-order.rkt:379:33 ival-fmod [39] 35.2% ival-asin [40] 29.5% ival-atan [44] 5.0% --------------------------------------------------------------------------- ??? [19] 23.6% ??? [25] 76.4% [31] 6022(25.0%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:375:33 ival-pow [38] 55.4% sample-multi-bounded [41] 20.3% ival-mult [53] 13.9% mk-ival [43] 7.0% ??? [49] 3.3% --------------------------------------------------------------------------- ??? [26] 100.0% [32] 1246(5.2%) 0(0.0%) for-loop ...gg-cleanup/src/core/alt-table.rkt:55:2 errors [52] 65.7% loop [46] 18.0% atab-add-altn [48] 16.4% --------------------------------------------------------------------------- ??? [27] 100.0% [33] 1086(4.5%) 0(0.0%) egraph-run ...gg-cleanup/src/core/eggmath.rkt:51:0 egraph-add-exprs [42] 100.0% --------------------------------------------------------------------------- ??? [25] 100.0% [34] 614(2.5%) 406(1.7%) ??? ...ie/egg-cleanup/src/syntax/syntax.rkt:214:17 return/no-unsupplied [47] 33.9% --------------------------------------------------------------------------- ??? [26] 100.0% [35] 400(1.7%) 0(0.0%) ??? ...ects/racket/contract/private/guts.rkt:770:8 bigvalue? [45] 100.0% --------------------------------------------------------------------------- ??? [26] 100.0% [36] 216(0.9%) 0(0.0%) make-alt-table ...anup/src/core/alt-table.rkt:32:0 errors [52] 100.0% --------------------------------------------------------------------------- for-loop [29] 100.0% [37] 200(0.8%) 0(0.0%) rewrite-expression-head20 ...core/matcher.rkt:87:0 rewriter [50] 100.0% --------------------------------------------------------------------------- ??? [31] 100.0% [38] 3336(13.8%) 1640(6.8%) ival-pow .../egg-cleanup/src/biginterval.rkt:229:0 bfexpt [64] 19.5% bfodd? [60] 12.7% bfeven? [61] 12.1% bfceiling [58] 6.5% --------------------------------------------------------------------------- ??? [30] 100.0% [39] 2922(12.1%) 822(3.4%) ival-fmod ...egg-cleanup/src/biginterval.rkt:395:0 ival-div [51] 50.4% ival-mult [53] 7.5% bfceiling [58] 7.1% ival-fabs [62] 6.9% --------------------------------------------------------------------------- ??? [30] 100.0% [40] 2444(10.1%) 1830(7.6%) ival-asin ...egg-cleanup/src/biginterval.rkt:357:0 bfasin [57] 25.1% --------------------------------------------------------------------------- ??? [31] 100.0% [41] 1224(5.1%) 604(2.5%) sample-multi-bounded ...leanup/src/points.rkt:15:0 random-ranges [56] 50.7% --------------------------------------------------------------------------- egraph-run [33] 100.0% [42] 1086(4.5%) 0(0.0%) egraph-add-exprs ...nup/src/core/eggmath.rkt:111:0 ??? [54] 71.8% loop [55] 28.2% --------------------------------------------------------------------------- ??? [31] 100.0% [43] 424(1.8%) 0(0.0%) mk-ival ...ie/egg-cleanup/src/biginterval.rkt:65:0 bf [59] 100.0% --------------------------------------------------------------------------- ??? [30] 100.0% [44] 412(1.7%) 412(1.7%) ival-atan ...egg-cleanup/src/biginterval.rkt:325:0 --------------------------------------------------------------------------- ??? [35] 100.0% [45] 400(1.7%) 400(1.7%) bigvalue? ...egg-cleanup/src/syntax/types.rkt:19:0 --------------------------------------------------------------------------- for-loop [32] 100.0% [46] 224(0.9%) 224(0.9%) loop ...e/egg-cleanup/src/core/alt-table.rkt:177:2 --------------------------------------------------------------------------- ??? [34] 100.0% [47] 208(0.9%) 208(0.9%) return/no-unsupplied ...ct/private/arr-i.rkt:566:0 --------------------------------------------------------------------------- for-loop [32] 100.0% [48] 204(0.8%) 0(0.0%) atab-add-altn ...anup/src/core/alt-table.rkt:203:0 errors [52] 100.0% --------------------------------------------------------------------------- ??? [31] 100.0% [49] 200(0.8%) 200(0.8%) ??? ...cts/racket/contract/private/list.rkt:209:10 --------------------------------------------------------------------------- rewrite-expression-head20 [37] 100.0% [50] 200(0.8%) 0(0.0%) rewriter .../egg-cleanup/src/core/matcher.rkt:90:2 for-loop [63] 100.0% --------------------------------------------------------------------------- ival-fmod [39] 100.0% [51] 1472(6.1%) 424(1.8%) ival-div .../egg-cleanup/src/biginterval.rkt:155:0 bfexpt [64] 71.2% --------------------------------------------------------------------------- atab-add-altn [48] 16.5% make-alt-table [36] 17.4% for-loop [32] 66.1% [52] 1238(5.1%) 0(0.0%) errors .../herbie/egg-cleanup/src/points.rkt:201:0 for-loop [65] 82.6% eval-prog [67] 17.4% --------------------------------------------------------------------------- ival-fmod [39] 20.6% ??? [31] 79.4% [53] 1056(4.4%) 630(2.6%) ival-mult ...egg-cleanup/src/biginterval.rkt:119:0 bfexpt [64] 40.3% --------------------------------------------------------------------------- egraph-add-exprs [42] 100.0% [54] 780(3.2%) 0(0.0%) ??? ...rbie/egg-cleanup/src/core/simplify.rkt:93:6 for-loop [66] 100.0% --------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [6] 3.0% egraph-add-exprs [42] 4.6% gf [78] 15.0% syntax-e/no-taint [4] 29.0% loop [55] 48.5% [55] 746(3.1%) 306(1.3%) loop (unknown source) loop [55] 48.5% gf [78] 44.0% eval-prog [67] 3.0% --------------------------------------------------------------------------- sample-multi-bounded [41] 100.0% [56] 620(2.6%) 620(2.6%) random-ranges .../egg-cleanup/src/common.rkt:171:0 --------------------------------------------------------------------------- ival-asin [40] 100.0% [57] 614(2.5%) 0(0.0%) bfasin ...lib/math/private/bigfloat/mpfr.rkt:632:4 new-mpfr [71] 66.4% ??? [70] 33.6% --------------------------------------------------------------------------- ival-fmod [39] 48.8% ival-pow [38] 51.2% [58] 426(1.8%) 0(0.0%) bfceiling .../math/private/bigfloat/mpfr.rkt:730:4 ??? [70] 100.0% --------------------------------------------------------------------------- mk-ival [43] 100.0% [59] 424(1.8%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:615:2 new-mpfr [71] 52.8% ??? [70] 47.2% --------------------------------------------------------------------------- ival-pow [38] 100.0% [60] 424(1.8%) 0(0.0%) bfodd? ...lib/math/private/bigfloat/mpfr.rkt:817:0 new-mpz [68] 52.8% ??? [70] 47.2% --------------------------------------------------------------------------- ival-pow [38] 100.0% [61] 402(1.7%) 202(0.8%) bfeven? ...ib/math/private/bigfloat/mpfr.rkt:814:0 new-mpz [68] 49.8% --------------------------------------------------------------------------- ival-fmod [39] 100.0% [62] 202(0.8%) 0(0.0%) ival-fabs ...egg-cleanup/src/biginterval.rkt:367:0 bfexpt [64] 100.0% --------------------------------------------------------------------------- rewriter [50] 100.0% [63] 200(0.8%) 0(0.0%) for-loop .../egg-cleanup/src/core/matcher.rkt:92:4 matcher [69] 100.0% --------------------------------------------------------------------------- ival-fabs [62] 8.7% ival-mult [53] 18.3% ival-pow [38] 28.0% ival-div [51] 45.0% [64] 2328(9.7%) 218(0.9%) bfexpt ...lib/math/private/bigfloat/mpfr.rkt:835:4 new-mpfr [71] 46.9% ??? [70] 43.7% --------------------------------------------------------------------------- errors [52] 100.0% [65] 1022(4.2%) 200(0.8%) for-loop ...erbie/egg-cleanup/src/points.rkt:203:2 .../more-scheme.rkt:261:28 [6] 80.4% --------------------------------------------------------------------------- ??? [54] 100.0% [66] 780(3.2%) 780(3.2%) for-loop ...gg-cleanup/src/core/simplify.rkt:112:2 --------------------------------------------------------------------------- errors [52] 49.1% loop [55] 50.9% [67] 440(1.8%) 0(0.0%) eval-prog ...bie/egg-cleanup/src/programs.rkt:83:0 eval [72] 50.9% temp85_0 [73] 49.1% --------------------------------------------------------------------------- bfeven? [61] 47.2% bfodd? [60] 52.8% [68] 424(1.8%) 424(1.8%) new-mpz ...-lib/math/private/bigfloat/gmp.rkt:73:0 --------------------------------------------------------------------------- for-loop [63] 100.0% [69] 200(0.8%) 0(0.0%) matcher .../egg-cleanup/src/core/matcher.rkt:118:2 ??? [74] 100.0% --------------------------------------------------------------------------- bfodd? [60] 9.8% bf [59] 9.8% bfasin [57] 10.0% bfceiling [58] 20.8% bfexpt [64] 49.7% [70] 2050(8.5%) 2050(8.5%) ??? /opt/racket-7.5/collects/ffi/unsafe.rkt:938:4 --------------------------------------------------------------------------- bf [59] 13.0% bfasin [57] 23.7% bfexpt [64] 63.3% [71] 1724(7.2%) 1724(7.2%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:348:0 --------------------------------------------------------------------------- eval-prog [67] 100.0% [72] 224(0.9%) 0(0.0%) eval (unknown source) add-ns-scopes [75] 100.0% --------------------------------------------------------------------------- eval-prog [67] 100.0% [73] 216(0.9%) 0(0.0%) temp85_0 (unknown source) compile15 [76] 100.0% --------------------------------------------------------------------------- matcher [69] 100.0% [74] 200(0.8%) 0(0.0%) ??? ...bie/egg-cleanup/src/core/matcher.rkt:130:11 foldl [77] 100.0% --------------------------------------------------------------------------- eval [72] 100.0% [75] 224(0.9%) 0(0.0%) add-ns-scopes (unknown source) gf [78] 100.0% --------------------------------------------------------------------------- temp85_0 [73] 100.0% [76] 216(0.9%) 0(0.0%) compile15 (unknown source) temp91_0 [79] 100.0% --------------------------------------------------------------------------- ??? [74] 100.0% [77] 200(0.8%) 0(0.0%) foldl ....5/collects/racket/private/list.rkt:229:4 ??? [80] 100.0% --------------------------------------------------------------------------- add-ns-scopes [75] 8.5% loop [55] 91.5% [78] 440(1.8%) 224(0.9%) gf (unknown source) propagation-apply [81] 49.1% loop [55] 42.4% --------------------------------------------------------------------------- compile15 [76] 100.0% [79] 216(0.9%) 0(0.0%) temp91_0 (unknown source) temp118_0 [82] 100.0% --------------------------------------------------------------------------- foldl [77] 100.0% [80] 200(0.8%) 0(0.0%) ??? ...erbie/egg-cleanup/src/core/matcher.rkt:26:7 core457 [83] 100.0% --------------------------------------------------------------------------- gf [78] 100.0% [81] 216(0.9%) 216(0.9%) propagation-apply (unknown source) --------------------------------------------------------------------------- temp91_0 [79] 100.0% [82] 216(0.9%) 0(0.0%) temp118_0 (unknown source) expand-capturing-lifts [84] 100.0% --------------------------------------------------------------------------- ??? [80] 100.0% [83] 200(0.8%) 0(0.0%) core457 ...acket/contract/private/arr-i.rkt:976:19 un-dep/maybe-chaperone [85] 100.0% --------------------------------------------------------------------------- temp118_0 [82] 100.0% [84] 216(0.9%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [86] 100.0% --------------------------------------------------------------------------- core457 [83] 100.0% [85] 200(0.8%) 0(0.0%) un-dep/maybe-chaperone ...private/arr-i.rkt:1137:2 get/build-late-neg-projection [87] 100.0% --------------------------------------------------------------------------- expand-capturing-lifts [84] 100.0% [86] 216(0.9%) 0(0.0%) lambda-clause-expander (unknown source) finish-bodys [88] 100.0% --------------------------------------------------------------------------- single-or/c-late-neg-projection [89] 50.0% un-dep/maybe-chaperone [85] 50.0% [87] 200(0.8%) 200(0.8%) get/build-late-neg-projection ...te/guts.rkt:827:0 single-or/c-late-neg-projection [89] 50.0% --------------------------------------------------------------------------- lambda-clause-expander [86] 20.0% for-loop [90] 80.0% [88] 216(0.9%) 0(0.0%) finish-bodys (unknown source) for-loop [90] 100.0% --------------------------------------------------------------------------- get/build-late-neg-projection [87] 100.0% [89] 200(0.8%) 0(0.0%) single-or/c-late-neg-projection ...te/orc.rkt:85:0 get/build-late-neg-projection [87] 100.0% --------------------------------------------------------------------------- for-loop [90] 16.7% finish-bodys [88] 83.3% [90] 216(0.9%) 0(0.0%) for-loop (unknown source) finish-bodys [88] 66.7% for-loop [90] 16.7% syntax-e [2] 16.7% ---------------------------------------------------------------------------