Profiling results ----------------- Total cpu time observed: 58300ms (out of 55476ms) Number of samples taken: 48 (once every 1215ms) ======================================================================= Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ======================================================================= ??? [36] 100.0% [1] 58300(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [5] 100.0% ----------------------------------------------------------------------- ??? [36] 100.0% [2] 50258(86.2%) 0(0.0%) run-improve47 ...estversion/src/mainloop.rkt:345:0 for-loop [6] 100.0% ----------------------------------------------------------------------- matcher [33] 100.0% [3] 7290(12.5%) 0(0.0%) ??? ...ath-testversion/src/core/matcher.rkt:130:11 for-loop [7] 100.0% ----------------------------------------------------------------------- copying-mergesort [75] 100.0% [4] 248(0.4%) 0(0.0%) jloop ...2/collects/racket/private/sort.rkt:120:23 ??? [61] 100.0% ----------------------------------------------------------------------- profile-thunk16 [1] 100.0% [5] 58300(100.0%) 0(0.0%) run ...et-7.2/share/pkgs/profile-lib/main.rkt:39:2 ??? [36] 100.0% ----------------------------------------------------------------------- run-improve47 [2] 100.0% [6] 50258(86.2%) 0(0.0%) for-loop ...ath-testversion/src/mainloop.rkt:364:4 run-iter! [8] 100.0% ----------------------------------------------------------------------- ??? [3] 100.0% [7] 7290(12.5%) 0(0.0%) for-loop ...estversion/src/core/matcher.rkt:132:15 matcher [33] 100.0% ----------------------------------------------------------------------- for-loop [6] 100.0% [8] 50258(86.2%) 0(0.0%) run-iter! ...th-testversion/src/mainloop.rkt:326:0 simplify! [9] 53.6% gen-series! [10] 28.2% gen-rewrites! [11] 14.5% finalize-iter! [12] 3.1% choose-best-alt! [13] 0.5% ----------------------------------------------------------------------- run-iter! [8] 100.0% [9] 26962(46.2%) 0(0.0%) simplify! ...th-testversion/src/mainloop.rkt:223:0 ??? [14] 100.0% ----------------------------------------------------------------------- run-iter! [8] 100.0% [10] 14180(24.3%) 0(0.0%) gen-series! ...-testversion/src/mainloop.rkt:159:0 for-loop [15] 100.0% ----------------------------------------------------------------------- run-iter! [8] 100.0% [11] 7290(12.5%) 0(0.0%) gen-rewrites! ...estversion/src/mainloop.rkt:182:0 for-loop [16] 100.0% ----------------------------------------------------------------------- run-iter! [8] 100.0% [12] 1576(2.7%) 0(0.0%) finalize-iter! ...stversion/src/mainloop.rkt:277:0 ??? [17] 100.0% ----------------------------------------------------------------------- run-iter! [8] 100.0% [13] 250(0.4%) 0(0.0%) choose-best-alt! ...version/src/mainloop.rkt:116:0 ??? [17] 100.0% ----------------------------------------------------------------------- simplify! [9] 100.0% [14] 26962(46.2%) 0(0.0%) ??? ...math-testversion/src/core/simplify.rkt:38:0 egraph-run [18] 100.0% ----------------------------------------------------------------------- gen-series! [10] 100.0% [15] 14180(24.3%) 0(0.0%) for-loop ...ath-testversion/src/mainloop.rkt:166:7 taylor-alt [19] 100.0% ----------------------------------------------------------------------- gen-rewrites! [11] 100.0% [16] 7290(12.5%) 0(0.0%) for-loop ...th-testversion/src/mainloop.rkt:190:11 rewrite-expression-head20 [20]100.0% ----------------------------------------------------------------------- choose-best-alt! [13] 13.7% finalize-iter! [12] 86.3% [17] 1826(3.1%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:390:18 for-loop [21] 86.3% ??? [22] 13.7% ----------------------------------------------------------------------- ??? [14] 100.0% [18] 26962(46.2%) 0(0.0%) egraph-run ...estversion/src/core/eggmath.rkt:55:0 egraph-add-exprs [23] 100.0% ----------------------------------------------------------------------- for-loop [15] 100.0% [19] 14180(24.3%) 0(0.0%) taylor-alt ...h-testversion/src/mainloop.rkt:145:0 for-loop [24] 100.0% ----------------------------------------------------------------------- for-loop [16] 100.0% [20] 7290(12.5%) 0(0.0%) rewrite-expression-head20 ...core/matcher.rkt:87:0 rewriter [25] 100.0% ----------------------------------------------------------------------- ??? [17] 100.0% [21] 1576(2.7%) 0(0.0%) for-loop ...estversion/src/core/alt-table.rkt:55:2 atab-add-altn [26] 66.5% errors [31] 33.5% ----------------------------------------------------------------------- ??? [17] 100.0% [22] 250(0.4%) 0(0.0%) ??? ...ath-testversion/src/core/alt-table.rkt:58:0 argmin [47] 100.0% ----------------------------------------------------------------------- egraph-run [18] 100.0% [23] 26962(46.2%) 0(0.0%) egraph-add-exprs ...ion/src/core/eggmath.rkt:119:0 ??? [27] 82.9% for-loop [30] 17.1% ----------------------------------------------------------------------- taylor-alt [19] 100.0% [24] 14180(24.3%) 0(0.0%) for-loop ...ath-testversion/src/mainloop.rkt:151:6 ??? [64] 100.0% ----------------------------------------------------------------------- matcher [33] 50.0% rewrite-expression-head20 [20] 50.0% [25] 7290(12.5%) 0(0.0%) rewriter ...-testversion/src/core/matcher.rkt:90:2 for-loop [29] 50.0% *rules* [28] 50.0% ----------------------------------------------------------------------- for-loop [21] 100.0% [26] 1048(1.8%) 0(0.0%) atab-add-altn ...sion/src/core/alt-table.rkt:203:0 errors [31] 100.0% ----------------------------------------------------------------------- egraph-add-exprs [23] 100.0% [27] 22356(38.3%) 22356(38.3%) ??? ...math-testversion/src/core/simplify.rkt:49:8 ----------------------------------------------------------------------- rewriter [25] 100.0% [28] 7290(12.5%) 0(0.0%) *rules* ...-testversion/src/syntax/rules.rkt:669:0 for-loop [32] 100.0% ----------------------------------------------------------------------- rewriter [25] 100.0% [29] 7290(12.5%) 0(0.0%) for-loop ...-testversion/src/core/matcher.rkt:92:4 matcher [33] 100.0% ----------------------------------------------------------------------- egraph-add-exprs [23] 100.0% [30] 4606(7.9%) 4606(7.9%) for-loop ...testversion/src/core/eggmath.rkt:127:4 ----------------------------------------------------------------------- ??? [52] 13.7% for-loop [21] 28.9% atab-add-altn [26] 57.4% [31] 1826(3.1%) 0(0.0%) errors ...eggmath-testversion/src/points.rkt:205:0 for-loop [34] 86.0% eval-prog [35] 14.0% ----------------------------------------------------------------------- *rules* [28] 100.0% [32] 7290(12.5%) 252(0.4%) for-loop ...ggmath-testversion/src/common.rkt:74:9 ormap [37] 92.9% ??? [38] 3.6% ----------------------------------------------------------------------- for-loop [7] 50.0% for-loop [29] 50.0% [33] 7290(12.5%) 0(0.0%) matcher ...-testversion/src/core/matcher.rkt:118:2 ??? [3] 50.0% rewriter [25] 50.0% ----------------------------------------------------------------------- errors [31] 100.0% [34] 1570(2.7%) 524(0.9%) for-loop ...gmath-testversion/src/points.rkt:207:2 ??? [36] 66.6% ----------------------------------------------------------------------- errors [31] 100.0% [35] 256(0.4%) 0(0.0%) eval-prog ...ath-testversion/src/programs.rkt:83:0 inductor [39] 100.0% ----------------------------------------------------------------------- for-loop [34] 0.6% run [5] 49.7% [36] 58300(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 49.7% run-improve47 [2] 42.8% prepare-points-intervals [40] 5.1% oracle-error [41] 1.1% ??? [61] 0.6% get-final-combination [42] 0.4% baseline-error [43] 0.2% ----------------------------------------------------------------------- for-loop [32] 100.0% [37] 6772(11.6%) 6772(11.6%) ormap ....2/collects/racket/private/map.rkt:141:13 ----------------------------------------------------------------------- for-loop [32] 100.0% [38] 266(0.5%) 266(0.5%) ??? ...cket-7.2/collects/racket/function.rkt:93:12 ----------------------------------------------------------------------- eval-prog [35] 25.0% inductor [39] 75.0% [39] 256(0.4%) 256(0.4%) inductor ...ath-testversion/src/programs.rkt:104:4 inductor [39] 75.0% ----------------------------------------------------------------------- ??? [36] 100.0% [40] 5998(10.3%) 0(0.0%) prepare-points-intervals ...n/src/points.rkt:110:0 loop [44] 100.0% ----------------------------------------------------------------------- ??? [36] 100.0% [41] 1330(2.3%) 0(0.0%) oracle-error ...h-testversion/src/points.rkt:188:0 for-loop [45] 82.4% loop [49] 17.6% ----------------------------------------------------------------------- ??? [36] 100.0% [42] 500(0.9%) 0(0.0%) get-final-combination ...on/src/mainloop.rkt:370:0 infer-splitpoints [46] 100.0% ----------------------------------------------------------------------- ??? [36] 100.0% [43] 214(0.4%) 0(0.0%) baseline-error ...testversion/src/points.rkt:192:0 argmin [47] 100.0% ----------------------------------------------------------------------- prepare-points-intervals [40] 100.0% [44] 5998(10.3%) 0(0.0%) loop ...e/eggmath-testversion/src/points.rkt:123:4 loop [48] 78.0% ??? [50] 22.0% ----------------------------------------------------------------------- oracle-error [41] 100.0% [45] 1096(1.9%) 0(0.0%) for-loop ...gmath-testversion/src/points.rkt:189:2 loop [49] 100.0% ----------------------------------------------------------------------- get-final-combination [42] 100.0% [46] 500(0.9%) 0(0.0%) infer-splitpoints ...ion/src/core/regimes.rkt:34:0 for-loop [51] 100.0% ----------------------------------------------------------------------- baseline-error [43] 46.1% ??? [22] 53.9% [47] 464(0.8%) 0(0.0%) argmin ...acket-7.2/collects/racket/list.rkt:765:0 ??? [52] 53.9% ??? [53] 46.1% ----------------------------------------------------------------------- loop [44] 100.0% [48] 4680(8.0%) 0(0.0%) loop ...ie/eggmath-testversion/src/points.rkt:81:2 ??? [61] 100.0% ----------------------------------------------------------------------- oracle-error [41] 17.6% loop [49] 37.7% for-loop [45] 44.7% [49] 1330(2.3%) 240(0.4%) loop ...-7.2/collects/racket/private/map.rkt:40:19 ??? [54] 44.2% loop [49] 37.7% ----------------------------------------------------------------------- loop [44] 100.0% [50] 1318(2.3%) 0(0.0%) ??? ...ie/eggmath-testversion/src/points.rkt:106:2 ??? [64] 77.2% compose [56] 22.8% ----------------------------------------------------------------------- infer-splitpoints [46] 100.0% [51] 500(0.9%) 0(0.0%) for-loop ...-testversion/src/core/regimes.rkt:47:6 option-on-expr [55] 100.0% ----------------------------------------------------------------------- argmin [47] 100.0% [52] 250(0.4%) 0(0.0%) ??? ...eggmath-testversion/src/mainloop.rkt:113:10 errors [31] 100.0% ----------------------------------------------------------------------- argmin [47] 100.0% [53] 214(0.4%) 0(0.0%) ??? ...e/eggmath-testversion/src/points.rkt:193:27 eval-errors [57] 100.0% ----------------------------------------------------------------------- loop [49] 100.0% [54] 1090(1.9%) 0(0.0%) ??? ...e/eggmath-testversion/src/points.rkt:190:26 ??? [61] 100.0% ----------------------------------------------------------------------- for-loop [51] 100.0% [55] 500(0.9%) 0(0.0%) option-on-expr ...rsion/src/core/regimes.rkt:126:0 err-lsts->split-indices [58] 50.4% sort-context-on-expr [59] 49.6% ----------------------------------------------------------------------- ??? [50] 100.0% [56] 300(0.5%) 300(0.5%) compose ...collects/racket/private/list.rkt:385:12 ----------------------------------------------------------------------- ??? [53] 100.0% [57] 214(0.4%) 0(0.0%) eval-errors ...th-testversion/src/points.rkt:179:0 for-loop [60] 100.0% ----------------------------------------------------------------------- option-on-expr [55] 100.0% [58] 252(0.4%) 0(0.0%) err-lsts->split-indices .../core/regimes.rkt:262:0 loop [62] 100.0% ----------------------------------------------------------------------- option-on-expr [55] 100.0% [59] 248(0.4%) 0(0.0%) sort-context-on-expr ...src/core/regimes.rkt:119:0 ??? [63] 100.0% ----------------------------------------------------------------------- eval-errors [57] 100.0% [60] 214(0.4%) 0(0.0%) for-loop ...gmath-testversion/src/points.rkt:181:2 ??? [61] 100.0% ----------------------------------------------------------------------- for-loop [60] 2.9% jloop [4] 3.4% ??? [36] 14.4% ??? [54] 15.0% loop [48] 64.3% [61] 7278(12.5%) 0(0.0%) ??? .../eggmath-testversion/src/programs.rkt:115:2 ??? [65] 77.2% ??? [64] 22.8% ----------------------------------------------------------------------- err-lsts->split-indices [58] 100.0% [62] 252(0.4%) 0(0.0%) loop ...ath-testversion/src/core/regimes.rkt:313:4 for-loop [66] 100.0% ----------------------------------------------------------------------- sort-context-on-expr [59] 100.0% [63] 248(0.4%) 0(0.0%) ??? ...-7.2/collects/racket/private/sort.rkt:369:3 generic-sort/key [67] 100.0% ----------------------------------------------------------------------- ??? [50] 6.0% ??? [61] 9.9% location-do [68] 42.1% for-loop [24] 42.1% [64] 16860(28.9%) 272(0.5%) ??? ...tract/private/arrow-higher-order.rkt:360:33 location-do [68] 42.1% approximate9 [69] 42.1% mk-ival [72] 8.2% sample-multi-bounded [73] 6.0% ----------------------------------------------------------------------- ??? [61] 100.0% [65] 5616(9.6%) 234(0.4%) ??? ...t-7.2/collects/racket/private/kw.rkt:594:14 ??? [70] 58.6% ??? [71] 37.3% ----------------------------------------------------------------------- loop [62] 100.0% [66] 252(0.4%) 0(0.0%) for-loop ...testversion/src/core/regimes.rkt:278:4 for-loop [74] 100.0% ----------------------------------------------------------------------- ??? [63] 100.0% [67] 248(0.4%) 0(0.0%) generic-sort/key .../racket/private/sort.rkt:156:2 copying-mergesort [75] 100.0% ----------------------------------------------------------------------- location-do [68] 8.7% ??? [64] 91.3% [68] 14180(24.3%) 0(0.0%) location-do ...h-testversion/src/programs.rkt:64:0 ??? [64] 91.3% location-do [68] 8.7% ----------------------------------------------------------------------- ??? [64] 100.0% [69] 14180(24.3%) 0(0.0%) approximate9 ...stversion/src/core/taylor.rkt:12:0 simplify [76] 89.1% loop [78] 10.9% ----------------------------------------------------------------------- ??? [65] 100.0% [70] 3290(5.6%) 724(1.2%) ??? ...tract/private/arrow-higher-order.rkt:364:33 ival-atan [77] 50.2% ival-add [82] 17.5% ival-sub [83] 10.3% ----------------------------------------------------------------------- ??? [65] 100.0% [71] 2092(3.6%) 1338(2.3%) ??? ...th-testversion/src/syntax/syntax.rkt:214:17 return/no-unsupplied [80] 36.0% ----------------------------------------------------------------------- ??? [64] 100.0% [72] 1390(2.4%) 0(0.0%) mk-ival ...th-testversion/src/biginterval.rkt:57:0 bf [79] 100.0% ----------------------------------------------------------------------- ??? [64] 100.0% [73] 1018(1.7%) 0(0.0%) sample-multi-bounded ...ersion/src/points.rkt:15:0 random-ranges [81] 71.7% list-member? [84] 28.3% ----------------------------------------------------------------------- for-loop [66] 100.0% [74] 252(0.4%) 252(0.4%) for-loop ...testversion/src/core/regimes.rkt:282:8 ----------------------------------------------------------------------- generic-sort/key [67] 25.0% copying-mergesort [75] 75.0% [75] 248(0.4%) 0(0.0%) copying-mergesort ...racket/private/sort.rkt:129:8 copying-mergesort [75] 75.0% jloop [4] 25.0% ----------------------------------------------------------------------- approximate9 [69] 100.0% [76] 12632(21.7%) 0(0.0%) simplify ...h-testversion/src/core/reduce.rkt:16:0 f67 [85] 100.0% ----------------------------------------------------------------------- ??? [70] 100.0% [77] 1650(2.8%) 1092(1.9%) ival-atan ...testversion/src/biginterval.rkt:311:0 bfatan [88] 33.8% ----------------------------------------------------------------------- approximate9 [69] 100.0% [78] 1548(2.7%) 0(0.0%) loop ...gmath-testversion/src/core/taylor.rkt:87:4 get-coeffs [86] 100.0% ----------------------------------------------------------------------- mk-ival [72] 100.0% [79] 1390(2.4%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [92] 80.6% ??? [94] 19.4% ----------------------------------------------------------------------- ??? [71] 100.0% [80] 754(1.3%) 498(0.9%) return/no-unsupplied ...ct/private/arr-i.rkt:542:0 filter [90] 34.0% ----------------------------------------------------------------------- sample-multi-bounded [73] 100.0% [81] 730(1.3%) 0(0.0%) random-ranges ...-testversion/src/common.rkt:183:0 build--> [89] 67.9% listof [93] 32.1% ----------------------------------------------------------------------- ??? [70] 100.0% [82] 576(1.0%) 0(0.0%) ival-add ...h-testversion/src/biginterval.rkt:93:0 bfsub [87] 100.0% ----------------------------------------------------------------------- ??? [70] 100.0% [83] 340(0.6%) 0(0.0%) ival-sub ...h-testversion/src/biginterval.rkt:99:0 bfsub [87] 100.0% ----------------------------------------------------------------------- sample-multi-bounded [73] 100.0% [84] 288(0.5%) 288(0.5%) list-member? ...llects/racket/private/set.rkt:24:0 ----------------------------------------------------------------------- simplify [76] 100.0% [85] 12632(21.7%) 0(0.0%) f67 ...2/collects/racket/match/compiler.rkt:507:40 composed [91] 100.0% ----------------------------------------------------------------------- loop [78] 100.0% [86] 1548(2.7%) 0(0.0%) get-coeffs ...testversion/src/core/taylor.rkt:65:2 listof [93] 100.0% ----------------------------------------------------------------------- ival-sub [83] 37.1% ival-add [82] 62.9% [87] 916(1.6%) 0(0.0%) bfsub ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [92] 62.9% ??? [94] 37.1% ----------------------------------------------------------------------- ival-atan [77] 100.0% [88] 558(1.0%) 0(0.0%) bfatan ...lib/math/private/bigfloat/mpfr.rkt:618:4 new-mpfr [92] 100.0% ----------------------------------------------------------------------- random-ranges [81] 100.0% [89] 496(0.9%) 0(0.0%) build--> ...act/private/arrow-val-first.rkt:1088:0 coerce-contract [96] 100.0% ----------------------------------------------------------------------- return/no-unsupplied [80] 100.0% [90] 256(0.4%) 256(0.4%) filter ...2/collects/racket/private/list.rkt:256:2 ----------------------------------------------------------------------- f67 [85] 100.0% [91] 12632(21.7%) 0(0.0%) composed ...ollects/racket/private/list.rkt:321:16 free-variables [95] 100.0% ----------------------------------------------------------------------- bfatan [88] 24.8% bfsub [87] 25.6% bf [79] 49.7% [92] 2254(3.9%) 2254(3.9%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ----------------------------------------------------------------------- random-ranges [81] 13.1% get-coeffs [86] 86.9% [93] 1782(3.1%) 0(0.0%) listof .../racket/contract/private/guts.rkt:504:15 coerce-contract [96] 100.0% ----------------------------------------------------------------------- bf [79] 44.3% bfsub [87] 55.7% [94] 610(1.0%) 610(1.0%) ??? /opt/racket-7.2/collects/ffi/unsafe.rkt:905:4 ----------------------------------------------------------------------- composed [91] 14.3% append-map [98] 85.7% [95] 12632(21.7%) 0(0.0%) free-variables ...estversion/src/programs.rkt:57:0 append-map [98] 85.7% constant? [97] 14.3% ----------------------------------------------------------------------- build--> [89] 21.8% listof [93] 78.2% [96] 2278(3.9%) 0(0.0%) coerce-contract ...contract/private/guts.rkt:308:0 coerce-contract/f [99] 100.0% ----------------------------------------------------------------------- free-variables [95] 100.0% [97] 12632(21.7%) 12632(21.7%) constant? ...stversion/src/syntax/syntax.rkt:726:0 ----------------------------------------------------------------------- free-variables [95] 100.0% [98] 12632(21.7%) 0(0.0%) append-map ...t-7.2/collects/racket/list.rkt:586:2 free-variables [95] 100.0% ----------------------------------------------------------------------- coerce-contract [96] 100.0% [99] 2278(3.9%) 1548(2.7%) coerce-contract/f ...ntract/private/guts.rkt:373:0 coerce-simple-value [100] 32.0% ----------------------------------------------------------------------- coerce-contract/f [99] 100.0% [100] 730(1.3%) 730(1.3%) coerce-simple-value ...ract/private/guts.rkt:386:0 -----------------------------------------------------------------------