Profiling results ----------------- Total cpu time observed: 52804ms (out of 53856ms) Number of samples taken: 141 (once every 374ms) ==================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ==================================================================== ??? [8] 100.0% [1] 52804(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [3] 100.0% -------------------------------------------------------------------- ??? [8] 100.0% [2] 296(0.6%) 0(0.0%) get-final-combination ...up/src/mainloop.rkt:365:0 argmin [4] 100.0% -------------------------------------------------------------------- profile-thunk16 [1] 100.0% [3] 52804(100.0%) 0(0.0%) run ...et-7.0/share/pkgs/profile-lib/main.rkt:39:2 ??? [8] 100.0% -------------------------------------------------------------------- get-final-combination [2] 100.0% [4] 296(0.6%) 0(0.0%) argmin ...acket-7.0/collects/racket/list.rkt:765:0 ??? [5] 100.0% -------------------------------------------------------------------- argmin [4] 100.0% [5] 296(0.6%) 0(0.0%) ??? ...bie/various-cleanup/src/mainloop.rkt:111:10 errors [6] 100.0% -------------------------------------------------------------------- ??? [5] 22.6% make-alt-table [47] 22.9% ??? [8] 54.4% [6] 1308(2.5%) 0(0.0%) errors ...bie/various-cleanup/src/points.rkt:205:0 for-loop [7] 100.0% -------------------------------------------------------------------- errors [6] 100.0% [7] 1308(2.5%) 360(0.7%) for-loop ...e/various-cleanup/src/points.rkt:207:2 ??? [8] 72.5% -------------------------------------------------------------------- for-loop [66] 0.2% for-loop [7] 0.6% run [3] 49.6% [8] 52804(100.0%) 296(0.6%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 49.6% prepare-points [14] 40.7% run-improve47 [9] 6.9% oracle-error [10] 0.6% errors [6] 0.6% ??? [32] 0.4% eval-errors [11] 0.3% errors-score [13] 0.3% eval-const-expr [12] 0.2% get-final-combination [2] 0.2% -------------------------------------------------------------------- ??? [8] 100.0% [9] 7530(14.3%) 0(0.0%) run-improve47 ...us-cleanup/src/mainloop.rkt:342:0 simplify! [15] 87.9% prepare-points [14] 8.1% ??? [35] 4.0% -------------------------------------------------------------------- ??? [8] 100.0% [10] 618(1.2%) 0(0.0%) oracle-error ...rious-cleanup/src/points.rkt:188:0 for-loop [16] 100.0% -------------------------------------------------------------------- ??? [8] 100.0% [11] 330(0.6%) 0(0.0%) eval-errors ...arious-cleanup/src/points.rkt:179:0 for-loop [17] 100.0% -------------------------------------------------------------------- ??? [8] 100.0% [12] 310(0.6%) 0(0.0%) eval-const-expr ...-cleanup/src/programs.rkt:111:0 eval-prog [18] 100.0% -------------------------------------------------------------------- ??? [8] 100.0% [13] 308(0.6%) 0(0.0%) errors-score ...rious-cleanup/src/points.rkt:196:0 loop [19] 100.0% -------------------------------------------------------------------- run-improve47 [9] 1.4% ??? [8] 98.6% [14] 43622(82.6%) 320(0.6%) prepare-points ...ous-cleanup/src/points.rkt:146:0 loop [20] 99.3% -------------------------------------------------------------------- run-improve47 [9] 100.0% [15] 6618(12.5%) 0(0.0%) simplify! ...arious-cleanup/src/mainloop.rkt:220:0 ??? [21] 100.0% -------------------------------------------------------------------- oracle-error [10] 100.0% [16] 618(1.2%) 0(0.0%) for-loop ...e/various-cleanup/src/points.rkt:189:2 map [22] 52.1% loop [25] 47.9% -------------------------------------------------------------------- eval-errors [11] 100.0% [17] 330(0.6%) 0(0.0%) for-loop ...e/various-cleanup/src/points.rkt:181:2 ??? [32] 100.0% -------------------------------------------------------------------- eval-const-expr [12] 100.0% [18] 310(0.6%) 0(0.0%) eval-prog ...various-cleanup/src/programs.rkt:83:0 temp68_2 [23] 100.0% -------------------------------------------------------------------- errors-score [13] 100.0% [19] 308(0.6%) 0(0.0%) loop .../racket-7.0/collects/racket/list.rkt:559:2 ordinary-value? [24] 100.0% -------------------------------------------------------------------- prepare-points [14] 100.0% [20] 43302(82.0%) 300(0.6%) loop ...erbie/various-cleanup/src/points.rkt:122:4 loop [26] 95.6% ??? [28] 3.8% -------------------------------------------------------------------- simplify! [15] 100.0% [21] 6618(12.5%) 0(0.0%) ??? .../various-cleanup/src/core/simplify.rkt:26:0 for-loop [27] 95.1% loop [30] 4.9% -------------------------------------------------------------------- for-loop [16] 100.0% [22] 322(0.6%) 0(0.0%) map ...t-7.0/collects/racket/private/map.rkt:35:13 ??? [29] 100.0% -------------------------------------------------------------------- eval-prog [18] 100.0% [23] 310(0.6%) 0(0.0%) temp68_2 (unknown source) compile16 [31] 100.0% -------------------------------------------------------------------- loop [19] 100.0% [24] 308(0.6%) 308(0.6%) ordinary-value? ...ious-cleanup/src/float.rkt:67:0 -------------------------------------------------------------------- for-loop [16] 100.0% [25] 296(0.6%) 0(0.0%) loop ...-7.0/collects/racket/private/map.rkt:40:19 ??? [29] 100.0% -------------------------------------------------------------------- loop [20] 100.0% [26] 41376(78.4%) 1336(2.5%) loop ...herbie/various-cleanup/src/points.rkt:81:2 ??? [32] 90.8% log! [34] 3.0% ??? [35] 1.5% ??? [92] 1.5% -------------------------------------------------------------------- ??? [21] 100.0% [27] 6296(11.9%) 0(0.0%) for-loop ...ous-cleanup/src/core/simplify.rkt:35:2 one-iter [33] 100.0% -------------------------------------------------------------------- loop [20] 100.0% [28] 1626(3.1%) 0(0.0%) ??? ...herbie/various-cleanup/src/points.rkt:106:2 ??? [49] 78.1% compose [36] 21.9% -------------------------------------------------------------------- loop [25] 47.9% map [22] 52.1% [29] 618(1.2%) 0(0.0%) ??? ...erbie/various-cleanup/src/points.rkt:190:26 ??? [32] 100.0% -------------------------------------------------------------------- ??? [21] 100.0% [30] 322(0.6%) 0(0.0%) loop ...rious-cleanup/src/core/extraction.rkt:25:2 for-loop [37] 100.0% -------------------------------------------------------------------- temp68_2 [23] 100.0% [31] 310(0.6%) 0(0.0%) compile16 (unknown source) temp74_0 [38] 100.0% -------------------------------------------------------------------- for-loop [17] 0.8% ??? [29] 1.6% ??? [8] 1.7% loop [26] 95.9% [32] 39178(74.2%) 298(0.6%) ??? ...rbie/various-cleanup/src/programs.rkt:108:2 ??? [39] 89.2% ??? [49] 8.4% real->precision [45] 0.8% ??? [48] 0.8% -------------------------------------------------------------------- for-loop [27] 100.0% [33] 6296(11.9%) 0(0.0%) one-iter ...ous-cleanup/src/core/simplify.rkt:72:0 for-loop [40] 76.0% find-matches [41] 19.1% for-loop [46] 4.9% -------------------------------------------------------------------- loop [26] 100.0% [34] 1238(2.3%) 288(0.5%) log! ...herbie/various-cleanup/src/points.rkt:61:2 for-loop [42] 76.7% -------------------------------------------------------------------- run-improve47 [9] 32.8% loop [26] 67.2% [35] 914(1.7%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:388:18 ??? [43] 67.2% make-alt-table [47] 32.8% -------------------------------------------------------------------- ??? [28] 100.0% [36] 356(0.7%) 356(0.7%) compose ...collects/racket/private/list.rkt:385:12 -------------------------------------------------------------------- loop [30] 100.0% [37] 322(0.6%) 0(0.0%) for-loop ...s-cleanup/src/core/extraction.rkt:29:4 for-loop [44] 100.0% -------------------------------------------------------------------- compile16 [31] 100.0% [38] 310(0.6%) 0(0.0%) temp74_0 (unknown source) loop [83] 100.0% -------------------------------------------------------------------- ??? [32] 100.0% [39] 34954(66.2%) 1022(1.9%) ??? ...t-7.0/collects/racket/private/kw.rkt:592:14 ??? [48] 65.3% ??? [49] 29.9% ??? [52] 2.0% -------------------------------------------------------------------- one-iter [33] 100.0% [40] 4782(9.1%) 0(0.0%) for-loop ...ous-cleanup/src/core/simplify.rkt:74:2 for-loop [50] 100.0% -------------------------------------------------------------------- one-iter [33] 100.0% [41] 1204(2.3%) 0(0.0%) find-matches ...cleanup/src/core/simplify.rkt:62:0 for-loop [51] 100.0% -------------------------------------------------------------------- log! [34] 100.0% [42] 950(1.8%) 950(1.8%) for-loop ...e/various-cleanup/src/points.rkt:68:23 -------------------------------------------------------------------- ??? [35] 100.0% [43] 614(1.2%) 0(0.0%) ??? ...ects/racket/contract/private/guts.rkt:746:8 bigvalue? [53] 100.0% -------------------------------------------------------------------- for-loop [37] 100.0% [44] 322(0.6%) 322(0.6%) for-loop ...-cleanup/src/core/extraction.rkt:37:16 -------------------------------------------------------------------- ??? [32] 100.0% [45] 322(0.6%) 0(0.0%) real->precision ...-cleanup/src/programs.rkt:87:33 infer-representation [54] 100.0% -------------------------------------------------------------------- one-iter [33] 100.0% [46] 310(0.6%) 0(0.0%) for-loop ...ous-cleanup/src/core/simplify.rkt:79:2 set-precompute! [55] 100.0% -------------------------------------------------------------------- ??? [35] 100.0% [47] 300(0.6%) 0(0.0%) make-alt-table ...anup/src/core/alt-table.rkt:33:0 errors [6] 100.0% -------------------------------------------------------------------- ??? [32] 1.3% ??? [39] 98.7% [48] 23104(43.8%) 2524(4.8%) ??? ...tract/private/arrow-higher-order.rkt:361:33 ival-fmod [56] 56.7% ival-sub [58] 16.2% ival-sinh [59] 16.2% -------------------------------------------------------------------- ??? [28] 8.5% ??? [32] 22.0% ??? [39] 69.5% [49] 15020(28.4%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:357:33 ival-pow [57] 69.5% mk-ival [60] 22.0% sample-multi-bounded [63] 8.5% -------------------------------------------------------------------- for-loop [40] 100.0% [50] 4782(9.1%) 0(0.0%) for-loop ...ous-cleanup/src/core/simplify.rkt:77:4 merge-egraph-nodes! [61] 53.5% dedup-children! [62] 40.3% substitute-e [67] 6.2% -------------------------------------------------------------------- for-loop [51] 50.0% find-matches [41] 50.0% [51] 1204(2.3%) 0(0.0%) for-loop ...ous-cleanup/src/core/simplify.rkt:64:8 for-loop [51] 50.0% match-e [64] 50.0% -------------------------------------------------------------------- ??? [39] 100.0% [52] 682(1.3%) 352(0.7%) ??? ...arious-cleanup/src/syntax/syntax.rkt:207:17 return/no-unsupplied [65] 48.4% -------------------------------------------------------------------- ??? [43] 100.0% [53] 614(1.2%) 614(1.2%) bigvalue? ...ous-cleanup/src/syntax/types.rkt:19:0 -------------------------------------------------------------------- real->precision [45] 100.0% [54] 322(0.6%) 322(0.6%) infer-representation ...cleanup/src/float.rkt:15:0 -------------------------------------------------------------------- for-loop [46] 100.0% [55] 310(0.6%) 0(0.0%) set-precompute! ...anup/src/core/simplify.rkt:86:0 for-loop [66] 100.0% -------------------------------------------------------------------- ??? [48] 100.0% [56] 13098(24.8%) 576(1.1%) ival-fmod ...ous-cleanup/src/biginterval.rkt:382:0 ival-div [68] 33.1% ival-fabs [70] 19.6% bftruncate [71] 17.9% bfdiv [80] 14.5% ival-mult [75] 8.0% bfsinh [81] 2.4% -------------------------------------------------------------------- ??? [49] 100.0% [57] 10442(19.8%) 5626(10.7%) ival-pow ...ious-cleanup/src/biginterval.rkt:216:0 bfdiv [80] 46.1% -------------------------------------------------------------------- ??? [48] 100.0% [58] 3748(7.1%) 1566(3.0%) ival-sub ...ious-cleanup/src/biginterval.rkt:100:0 bfdiv [80] 58.2% -------------------------------------------------------------------- ??? [48] 100.0% [59] 3734(7.1%) 1602(3.0%) ival-sinh ...ous-cleanup/src/biginterval.rkt:362:0 bfsinh [81] 57.1% -------------------------------------------------------------------- ??? [49] 100.0% [60] 3308(6.3%) 0(0.0%) mk-ival ...arious-cleanup/src/biginterval.rkt:57:0 bf [69] 100.0% -------------------------------------------------------------------- merge-egraph-nodes! [61] 14.8% for-loop [50] 85.2% [61] 2558(4.8%) 0(0.0%) merge-egraph-nodes! ...p/src/core/egraph.rkt:143:0 update-leader! [72] 60.4% loop! [77] 24.8% merge-egraph-nodes! [61] 14.8% -------------------------------------------------------------------- for-loop [50] 100.0% [62] 1926(3.6%) 0(0.0%) dedup-children! ...leanup/src/core/enode.rkt:195:0 loop [73] 68.8% loop [78] 31.2% -------------------------------------------------------------------- ??? [49] 100.0% [63] 1270(2.4%) 600(1.1%) sample-multi-bounded ...leanup/src/points.rkt:15:0 random-ranges [76] 52.8% -------------------------------------------------------------------- for-loop [85] 24.9% for-loop [51] 75.1% [64] 1204(2.3%) 0(0.0%) match-e ...arious-cleanup/src/core/ematch.rkt:39:0 for-loop [74] 100.0% -------------------------------------------------------------------- ??? [52] 100.0% [65] 330(0.6%) 330(0.6%) return/no-unsupplied ...ct/private/arr-i.rkt:534:0 -------------------------------------------------------------------- set-precompute! [55] 100.0% [66] 310(0.6%) 0(0.0%) for-loop ...ous-cleanup/src/core/simplify.rkt:88:2 ??? [8] 100.0% -------------------------------------------------------------------- for-loop [50] 100.0% [67] 298(0.6%) 0(0.0%) substitute-e ...s-cleanup/src/core/ematch.rkt:64:0 for-loop [79] 100.0% -------------------------------------------------------------------- ival-fmod [56] 100.0% [68] 4334(8.2%) 1216(2.3%) ival-div ...ious-cleanup/src/biginterval.rkt:148:0 bfdiv [80] 71.9% -------------------------------------------------------------------- mk-ival [60] 100.0% [69] 3308(6.3%) 652(1.2%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [91] 71.5% ??? [92] 8.8% -------------------------------------------------------------------- ival-fmod [56] 100.0% [70] 2572(4.9%) 1006(1.9%) ival-fabs ...ous-cleanup/src/biginterval.rkt:354:0 bfsinh [81] 60.9% -------------------------------------------------------------------- ival-fmod [56] 100.0% [71] 2346(4.4%) 608(1.2%) bftruncate ...math/private/bigfloat/mpfr.rkt:716:4 new-mpfr [91] 43.3% ??? [92] 30.8% -------------------------------------------------------------------- merge-egraph-nodes! [61] 100.0% [72] 1924(3.6%) 0(0.0%) update-leader! ...leanup/src/core/egraph.rkt:211:0 for-loop [82] 82.7% for-loop [87] 17.3% -------------------------------------------------------------------- dedup-children! [62] 15.4% loop [73] 84.6% [73] 1326(2.5%) 1326(2.5%) loop ...racket-7.0/collects/racket/list.rkt:434:17 loop [73] 84.6% -------------------------------------------------------------------- match-e [64] 100.0% [74] 1204(2.3%) 600(1.1%) for-loop ...ious-cleanup/src/core/ematch.rkt:52:11 foldr [88] 25.4% for-loop [85] 24.9% foldl [89] 24.8% -------------------------------------------------------------------- ival-fmod [56] 100.0% [75] 1048(2.0%) 0(0.0%) ival-mult ...ous-cleanup/src/biginterval.rkt:112:0 bfdiv [80] 100.0% -------------------------------------------------------------------- sample-multi-bounded [63] 100.0% [76] 670(1.3%) 300(0.6%) random-ranges ...ious-cleanup/src/common.rkt:183:0 cons/c [86] 55.2% -------------------------------------------------------------------- merge-egraph-nodes! [61] 100.0% [77] 634(1.2%) 0(0.0%) loop! .../various-cleanup/src/core/enode.rkt:187:2 custom-set-union [84] 100.0% -------------------------------------------------------------------- dedup-children! [62] 12.7% loop [78] 87.3% [78] 600(1.1%) 0(0.0%) loop ...racket-7.0/collects/racket/list.rkt:417:34 loop [78] 87.3% loop [83] 12.7% -------------------------------------------------------------------- substitute-e [67] 100.0% [79] 298(0.6%) 0(0.0%) for-loop ...ious-cleanup/src/core/ematch.rkt:73:24 mk-enode! [90] 100.0% -------------------------------------------------------------------- ival-mult [75] 8.0% ival-fmod [56] 14.6% ival-sub [58] 16.7% ival-div [68] 23.9% ival-pow [57] 36.9% [80] 13068(24.7%) 610(1.2%) bfdiv ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [91] 70.2% ??? [92] 25.1% -------------------------------------------------------------------- ival-fmod [56] 7.9% ival-fabs [70] 39.0% ival-sinh [59] 53.1% [81] 4016(7.6%) 0(0.0%) bfsinh ...lib/math/private/bigfloat/mpfr.rkt:618:4 ??? [92] 53.8% new-mpfr [91] 46.2% -------------------------------------------------------------------- update-leader! [72] 100.0% [82] 1592(3.0%) 0(0.0%) for-loop ...ious-cleanup/src/core/egraph.rkt:216:6 hash-update! [101] 81.2% for-loop [98] 18.8% -------------------------------------------------------------------- temp74_0 [38] 34.1% loop [78] 65.9% [83] 910(1.7%) 600(1.1%) loop (unknown source) expand-capturing-lifts [95] 34.1% -------------------------------------------------------------------- loop! [77] 100.0% [84] 634(1.2%) 0(0.0%) custom-set-union ...et/private/set-types.rkt:168:0 for-loop [94] 52.7% for-loop [97] 47.3% -------------------------------------------------------------------- for-loop [74] 100.0% [85] 600(1.1%) 0(0.0%) for-loop ...ious-cleanup/src/core/ematch.rkt:58:37 match-e [64] 100.0% -------------------------------------------------------------------- random-ranges [76] 100.0% [86] 370(0.7%) 0(0.0%) cons/c .../racket/contract/private/guts.rkt:478:15 coerce-contract [93] 100.0% -------------------------------------------------------------------- update-leader! [72] 100.0% [87] 332(0.6%) 332(0.6%) for-loop ...ious-cleanup/src/core/egraph.rkt:226:6 -------------------------------------------------------------------- for-loop [74] 100.0% [88] 306(0.6%) 0(0.0%) foldr ....0/collects/racket/private/list.rkt:242:4 cp-2 [96] 100.0% -------------------------------------------------------------------- for-loop [74] 100.0% [89] 298(0.6%) 0(0.0%) foldl ....0/collects/racket/private/list.rkt:229:4 merge2 [99] 100.0% -------------------------------------------------------------------- for-loop [79] 100.0% [90] 298(0.6%) 0(0.0%) mk-enode! ...ious-cleanup/src/core/egraph.rkt:97:0 new-enode [100] 100.0% -------------------------------------------------------------------- bftruncate [71] 7.0% bfsinh [81] 12.9% bf [69] 16.4% bfdiv [80] 63.7% [91] 14412(27.3%) 14412(27.3%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 -------------------------------------------------------------------- bf [69] 4.1% loop [26] 8.6% bftruncate [71] 10.2% bfsinh [81] 30.6% bfdiv [80] 46.4% [92] 7066(13.4%) 7066(13.4%) ??? /opt/racket-7.0/collects/ffi/unsafe.rkt:898:4 -------------------------------------------------------------------- cons/c [86] 100.0% [93] 370(0.7%) 0(0.0%) coerce-contract ...contract/private/guts.rkt:285:0 coerce-contract/f [102] 100.0% -------------------------------------------------------------------- custom-set-union [84] 100.0% [94] 334(0.6%) 334(0.6%) for-loop ...cts/racket/private/set-types.rkt:174:3 -------------------------------------------------------------------- loop [83] 100.0% [95] 310(0.6%) 310(0.6%) expand-capturing-lifts (unknown source) -------------------------------------------------------------------- foldr [88] 100.0% [96] 306(0.6%) 0(0.0%) cp-2 .../racket-7.0/collects/racket/list.rkt:826:2 for-loop [103] 100.0% -------------------------------------------------------------------- custom-set-union [84] 100.0% [97] 300(0.6%) 300(0.6%) for-loop ...cts/racket/private/set-types.rkt:152:2 -------------------------------------------------------------------- for-loop [82] 100.0% [98] 300(0.6%) 0(0.0%) for-loop ...ious-cleanup/src/core/egraph.rkt:217:8 hash-update! [101] 100.0% -------------------------------------------------------------------- foldl [89] 100.0% [99] 298(0.6%) 298(0.6%) merge2 ...various-cleanup/src/core/ematch.rkt:24:0 -------------------------------------------------------------------- mk-enode! [90] 100.0% [100] 298(0.6%) 0(0.0%) new-enode ...rious-cleanup/src/core/enode.rkt:94:0 type-of-enode-expr [104] 100.0% -------------------------------------------------------------------- for-loop [98] 18.8% for-loop [82] 81.2% [101] 1592(3.0%) 0(0.0%) hash-update! ...cket/private/more-scheme.rkt:365:4 ??? [105] 100.0% -------------------------------------------------------------------- coerce-contract [93] 100.0% [102] 370(0.7%) 0(0.0%) coerce-contract/f ...ntract/private/guts.rkt:350:0 coerce-simple-value [106] 100.0% -------------------------------------------------------------------- cp-2 [96] 100.0% [103] 306(0.6%) 306(0.6%) for-loop ...ket-7.0/collects/racket/list.rkt:827:4 -------------------------------------------------------------------- new-enode [100] 100.0% [104] 298(0.6%) 0(0.0%) type-of-enode-expr ...anup/src/core/enode.rkt:66:0 value? [107] 100.0% -------------------------------------------------------------------- hash-update! [101] 100.0% [105] 1592(3.0%) 0(0.0%) ??? .../various-cleanup/src/core/egraph.rkt:219:24 for-loop [108] 100.0% -------------------------------------------------------------------- coerce-contract/f [102] 100.0% [106] 370(0.7%) 370(0.7%) coerce-simple-value ...ract/private/guts.rkt:363:0 -------------------------------------------------------------------- type-of-enode-expr [104] 100.0% [107] 298(0.6%) 298(0.6%) value? ...arious-cleanup/src/syntax/types.rkt:18:0 -------------------------------------------------------------------- ??? [105] 100.0% [108] 1592(3.0%) 1592(3.0%) for-loop ...ous-cleanup/src/core/egraph.rkt:220:26 --------------------------------------------------------------------