Profiling results ----------------- Total cpu time observed: 39126ms (out of 39460ms) Number of samples taken: 127 (once every 308ms) (Hiding functions with self<1.0% and local<2.0%: 2 of 102 hidden) ================================================================= Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ================================================================= ??? [5] 100.0% [1] 39126(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [3] 100.0% ----------------------------------------------------------------- ??? [5] 100.0% [2] 646(1.7%) 0(0.0%) errors ...bie/various-cleanup/src/points.rkt:223:0 for-loop [4] 100.0% ----------------------------------------------------------------- profile-thunk16 [1] 100.0% [3] 39126(100.0%) 0(0.0%) run ...et-7.0/share/pkgs/profile-lib/main.rkt:39:2 ??? [5] 100.0% ----------------------------------------------------------------- errors [2] 100.0% [4] 646(1.7%) 0(0.0%) for-loop ...e/various-cleanup/src/points.rkt:225:2 ??? [5] 100.0% ----------------------------------------------------------------- for-loop [4] 0.6% run [3] 49.7% [5] 39126(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 49.7% run-improve43 [6] 41.1% prepare-points [7] 6.6% eval-errors [8] 0.7% errors [2] 0.6% errors-score [9] 0.4% oracle-error [10] 0.4% ??? [28] 0.3% ulp-difference [20] 0.3% ----------------------------------------------------------------- ??? [5] 100.0% [6] 32138(82.1%) 0(0.0%) run-improve43 ...us-cleanup/src/mainloop.rkt:339:0 simplify! [11] 100.0% ----------------------------------------------------------------- ??? [5] 100.0% [7] 5156(13.2%) 0(0.0%) prepare-points ...ous-cleanup/src/points.rkt:166:0 loop [12] 100.0% ----------------------------------------------------------------- ??? [5] 100.0% [8] 582(1.5%) 0(0.0%) eval-errors ...arious-cleanup/src/points.rkt:198:0 for-loop [13] 100.0% ----------------------------------------------------------------- ??? [5] 100.0% [9] 304(0.8%) 0(0.0%) errors-score ...rious-cleanup/src/points.rkt:215:0 loop [14] 100.0% ----------------------------------------------------------------- ??? [5] 100.0% [10] 300(0.8%) 0(0.0%) oracle-error ...rious-cleanup/src/points.rkt:207:0 for-loop [15] 100.0% ----------------------------------------------------------------- run-improve43 [6] 100.0% [11] 32138(82.1%) 0(0.0%) simplify! ...arious-cleanup/src/mainloop.rkt:217:0 ??? [16] 100.0% ----------------------------------------------------------------- prepare-points [7] 100.0% [12] 5156(13.2%) 288(0.7%) loop ...erbie/various-cleanup/src/points.rkt:143:4 loop [17] 69.9% ordinary-value? [18] 13.0% ??? [19] 11.5% ----------------------------------------------------------------- eval-errors [8] 100.0% [13] 582(1.5%) 0(0.0%) for-loop ...e/various-cleanup/src/points.rkt:200:2 ulp-difference [20] 50.2% ??? [37] 49.8% ----------------------------------------------------------------- errors-score [9] 100.0% [14] 304(0.8%) 304(0.8%) loop .../racket-7.0/collects/racket/list.rkt:559:2 ----------------------------------------------------------------- oracle-error [10] 100.0% [15] 300(0.8%) 0(0.0%) for-loop ...e/various-cleanup/src/points.rkt:208:2 map [21] 100.0% ----------------------------------------------------------------- simplify! [11] 100.0% [16] 32138(82.1%) 0(0.0%) ??? .../various-cleanup/src/core/simplify.rkt:26:0 for-loop [22] 84.3% loop [27] 15.7% ----------------------------------------------------------------- loop [12] 100.0% [17] 3604(9.2%) 300(0.8%) loop ...erbie/various-cleanup/src/points.rkt:102:2 ??? [28] 81.5% hash-update! [96] 10.2% ----------------------------------------------------------------- loop [12] 100.0% [18] 670(1.7%) 0(0.0%) ordinary-value? ...ious-cleanup/src/float.rkt:80:0 special-value? [23] 100.0% ----------------------------------------------------------------- loop [12] 100.0% [19] 594(1.5%) 0(0.0%) ??? ...herbie/various-cleanup/src/points.rkt:127:2 ??? [37] 100.0% ----------------------------------------------------------------- for-loop [13] 49.3% ??? [5] 50.7% [20] 592(1.5%) 300(0.8%) ulp-difference ...rious-cleanup/src/float.rkt:46:0 infer-representation [25] 49.3% ----------------------------------------------------------------- for-loop [15] 100.0% [21] 300(0.8%) 0(0.0%) map ...t-7.0/collects/racket/private/map.rkt:35:13 ??? [24] 100.0% ----------------------------------------------------------------- ??? [16] 100.0% [22] 27082(69.2%) 0(0.0%) for-loop ...ous-cleanup/src/core/simplify.rkt:35:2 one-iter [26] 87.9% loop [27] 12.1% ----------------------------------------------------------------- ordinary-value? [18] 100.0% [23] 670(1.7%) 0(0.0%) special-value? ...rious-cleanup/src/float.rkt:76:0 for-loop [29] 100.0% ----------------------------------------------------------------- map [21] 100.0% [24] 300(0.8%) 0(0.0%) ??? ...erbie/various-cleanup/src/points.rkt:209:26 ??? [28] 100.0% ----------------------------------------------------------------- ulp-difference [20] 100.0% [25] 292(0.7%) 292(0.7%) infer-representation ...cleanup/src/float.rkt:15:0 ----------------------------------------------------------------- for-loop [22] 100.0% [26] 23794(60.8%) 0(0.0%) one-iter ...ous-cleanup/src/core/simplify.rkt:92:0 for-loop [30] 63.6% find-matches [31] 36.4% ----------------------------------------------------------------- for-loop [22] 39.4% ??? [16] 60.6% [27] 8344(21.3%) 0(0.0%) loop ...arious-cleanup/src/core/simplify.rkt:158:2 for-loop [32] 100.0% ----------------------------------------------------------------- ??? [24] 8.4% ??? [5] 9.7% loop [17] 82.0% [28] 3582(9.2%) 300(0.8%) ??? ...erbie/various-cleanup/src/programs.rkt:99:2 ??? [33] 47.0% ??? [37] 44.7% ----------------------------------------------------------------- special-value? [23] 100.0% [29] 670(1.7%) 670(1.7%) for-loop ...bie/various-cleanup/src/float.rkt:17:3 ----------------------------------------------------------------- one-iter [26] 100.0% [30] 15136(38.7%) 0(0.0%) for-loop ...us-cleanup/src/core/simplify.rkt:101:2 loop [34] 91.8% update-leader! [71] 8.2% ----------------------------------------------------------------- one-iter [26] 100.0% [31] 8658(22.1%) 0(0.0%) find-matches ...cleanup/src/core/simplify.rkt:62:0 for-loop [35] 100.0% ----------------------------------------------------------------- loop [27] 100.0% [32] 8344(21.3%) 0(0.0%) for-loop ...us-cleanup/src/core/simplify.rkt:162:4 loop [36] 92.9% for-loop [39] 7.1% ----------------------------------------------------------------- ??? [28] 100.0% [33] 1682(4.3%) 0(0.0%) ??? ...t-7.0/collects/racket/private/kw.rkt:592:14 ??? [38] 62.4% ??? [37] 37.6% ----------------------------------------------------------------- loop [34] 1.2% for-loop [30] 98.8% [34] 13902(35.5%) 0(0.0%) loop ...arious-cleanup/src/core/simplify.rkt:106:4 apply-match [40] 98.8% loop [34] 1.2% ----------------------------------------------------------------- for-loop [35] 50.0% find-matches [31] 50.0% [35] 8658(22.1%) 596(1.5%) for-loop ...ous-cleanup/src/core/simplify.rkt:64:8 for-loop [35] 50.0% match-e [51] 43.1% ??? [47] 3.5% ----------------------------------------------------------------- for-loop [32] 100.0% [36] 7754(19.8%) 0(0.0%) loop .../racket-7.0/collects/racket/list.rkt:751:4 expression-cost [41] 58.0% for-loop [42] 42.0% ----------------------------------------------------------------- for-loop [13] 9.3% ??? [19] 19.1% ??? [33] 20.3% ??? [28] 51.3% [37] 3116(8.0%) 284(0.7%) ??? ...tract/private/arrow-higher-order.rkt:357:33 mk-ival [43] 40.2% ->flonum [45] 20.4% ival-mult [46] 20.3% sample-multi-bounded [49] 9.9% ----------------------------------------------------------------- ??? [33] 100.0% [38] 1050(2.7%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:361:33 ival-add [44] 64.2% ival-sub [48] 35.8% ----------------------------------------------------------------- for-loop [32] 100.0% [39] 590(1.5%) 300(0.8%) for-loop ...s-cleanup/src/core/simplify.rkt:166:16 for-loop [50] 49.2% ----------------------------------------------------------------- loop [34] 100.0% [40] 13902(35.5%) 0(0.0%) apply-match ...-cleanup/src/core/simplify.rkt:71:0 for-loop [52] 66.8% match-e [51] 33.2% ----------------------------------------------------------------- loop [36] 100.0% [41] 4498(11.5%) 300(0.8%) expression-cost ...-cleanup/src/programs.rkt:157:0 compile [53] 93.3% ----------------------------------------------------------------- loop [36] 100.0% [42] 3256(8.3%) 598(1.5%) for-loop ...various-cleanup/src/programs.rkt:158:2 table-ref [54] 81.6% ----------------------------------------------------------------- ??? [37] 100.0% [43] 1254(3.2%) 0(0.0%) mk-ival ...arious-cleanup/src/biginterval.rkt:57:0 bf [55] 100.0% ----------------------------------------------------------------- ??? [38] 100.0% [44] 674(1.7%) 356(0.9%) ival-add ...rious-cleanup/src/biginterval.rkt:94:0 bfsub [56] 47.2% ----------------------------------------------------------------- ??? [37] 100.0% [45] 636(1.6%) 290(0.7%) ->flonum ...ie/various-cleanup/src/float.rkt:149:0 ??? [57] 54.4% ----------------------------------------------------------------- ??? [37] 100.0% [46] 632(1.6%) 300(0.8%) ival-mult ...ous-cleanup/src/biginterval.rkt:112:0 bfsub [56] 52.5% ----------------------------------------------------------------- for-loop [35] 100.0% [47] 598(1.5%) 300(0.8%) ??? ...ie/various-cleanup/src/core/ematch.rkt:43:5 for-loop [58] 49.8% ----------------------------------------------------------------- ??? [38] 100.0% [48] 376(1.0%) 0(0.0%) ival-sub ...ious-cleanup/src/biginterval.rkt:100:0 bfsub [56] 100.0% ----------------------------------------------------------------- ??? [37] 100.0% [49] 310(0.8%) 310(0.8%) sample-multi-bounded ...leanup/src/points.rkt:15:0 ----------------------------------------------------------------- for-loop [39] 100.0% [50] 290(0.7%) 290(0.7%) for-loop ...s-cleanup/src/core/simplify.rkt:170:23 ----------------------------------------------------------------- apply-match [40] 24.7% for-loop [35] 35.9% for-loop [70] 39.4% [51] 12076(30.9%) 1486(3.8%) match-e ...arious-cleanup/src/core/ematch.rkt:39:0 for-loop [59] 80.8% constant? [63] 5.8% ??? [64] 2.7% variable? [65] 2.5% ----------------------------------------------------------------- apply-match [40] 100.0% [52] 9290(23.7%) 0(0.0%) for-loop ...ous-cleanup/src/core/simplify.rkt:84:2 merge-egraph-nodes! [60] 79.3% dedup-children! [73] 20.7% ----------------------------------------------------------------- expression-cost [41] 100.0% [53] 4198(10.7%) 0(0.0%) compile .../various-cleanup/src/programs.rkt:134:0 hash-ref! [61] 100.0% ----------------------------------------------------------------- for-loop [42] 100.0% [54] 2658(6.8%) 2076(5.3%) table-ref ...e/various-cleanup/src/common.rkt:41:0 assoc-iterate-first [68] 11.4% for-loop [69] 10.5% ----------------------------------------------------------------- mk-ival [43] 100.0% [55] 1254(3.2%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [66] 50.6% ??? [62] 49.4% ----------------------------------------------------------------- ival-add [44] 31.0% ival-mult [46] 32.4% ival-sub [48] 36.6% [56] 1026(2.6%) 0(0.0%) bfsub ...-lib/math/private/bigfloat/mpfr.rkt:821:4 ??? [62] 100.0% ----------------------------------------------------------------- ->flonum [45] 100.0% [57] 346(0.9%) 0(0.0%) ??? ...s/herbie/various-cleanup/src/float.rkt:23:2 for-loop [67] 100.0% ----------------------------------------------------------------- ??? [47] 100.0% [58] 298(0.8%) 298(0.8%) for-loop ...rious-cleanup/src/core/ematch.rkt:44:7 ----------------------------------------------------------------- match-e [51] 100.0% [59] 11478(29.3%) 2822(7.2%) for-loop ...ious-cleanup/src/core/ematch.rkt:52:11 for-loop [70] 57.8% loop [75] 11.5% foldr [77] 5.5% cartesian-product [80] 3.8% filter [79] 2.8% foldl [85] 1.3% ----------------------------------------------------------------- for-loop [78] 4.5% merge-egraph-nodes! [60] 13.1% for-loop [52] 82.4% [60] 7368(18.8%) 0(0.0%) merge-egraph-nodes! ...p/src/core/egraph.rkt:143:0 update-leader! [71] 66.2% loop! [76] 14.0% merge-egraph-nodes! [60] 13.1% for-loop [78] 4.5% dedup-children! [73] 2.2% ----------------------------------------------------------------- ??? [72] 46.0% compile [53] 54.0% [61] 4198(10.7%) 2670(6.8%) hash-ref! .../racket/private/more-scheme.rkt:376:2 ??? [72] 65.4% ----------------------------------------------------------------- bf [55] 37.7% bfsub [56] 62.3% [62] 1646(4.2%) 1646(4.2%) ??? /opt/racket-7.0/collects/ffi/unsafe.rkt:898:4 ----------------------------------------------------------------- match-e [51] 100.0% [63] 1202(3.1%) 0(0.0%) constant? ...s-cleanup/src/syntax/syntax.rkt:721:0 value? [74] 100.0% ----------------------------------------------------------------- match-e [51] 100.0% [64] 970(2.5%) 672(1.7%) ??? ...contract/private/arrow-val-first.rkt:388:18 ??? [81] 30.7% ----------------------------------------------------------------- match-e [51] 100.0% [65] 894(2.3%) 300(0.8%) variable? ...s-cleanup/src/syntax/syntax.rkt:724:0 value? [74] 66.4% ----------------------------------------------------------------- bf [55] 100.0% [66] 634(1.6%) 634(1.6%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ----------------------------------------------------------------- ??? [57] 100.0% [67] 346(0.9%) 346(0.9%) for-loop ...bie/various-cleanup/src/float.rkt:24:5 ----------------------------------------------------------------- table-ref [54] 100.0% [68] 302(0.8%) 302(0.8%) assoc-iterate-first ...cket/private/dict.rkt:166:0 ----------------------------------------------------------------- table-ref [54] 100.0% [69] 280(0.7%) 280(0.7%) for-loop ...ie/various-cleanup/src/common.rkt:43:4 ----------------------------------------------------------------- for-loop [59] 100.0% [70] 8584(21.9%) 320(0.8%) for-loop ...ious-cleanup/src/core/ematch.rkt:58:37 match-e [51] 98.1% ----------------------------------------------------------------- for-loop [30] 17.6% merge-egraph-nodes! [60] 82.4% [71] 7014(17.9%) 0(0.0%) update-leader! ...leanup/src/core/egraph.rkt:211:0 for-loop [82] 94.7% custom-set-union! [87] 5.3% ----------------------------------------------------------------- hash-ref! [61] 100.0% [72] 3614(9.2%) 1528(3.9%) ??? ...rbie/various-cleanup/src/programs.rkt:142:5 hash-ref! [61] 77.5% ----------------------------------------------------------------- merge-egraph-nodes! [60] 14.4% for-loop [52] 85.6% [73] 2246(5.7%) 0(0.0%) dedup-children! ...leanup/src/core/enode.rkt:198:0 loop [83] 86.3% loop [88] 13.7% ----------------------------------------------------------------- variable? [65] 33.1% constant? [63] 66.9% [74] 1796(4.6%) 1796(4.6%) value? ...arious-cleanup/src/syntax/types.rkt:18:0 ----------------------------------------------------------------- for-loop [59] 5.7% loop [75] 94.3% [75] 1316(3.4%) 332(0.8%) loop ...-7.0/collects/racket/private/map.rkt:40:19 loop [75] 94.3% curried [86] 4.3% foldl [85] 0.7% ----------------------------------------------------------------- merge-egraph-nodes! [60] 100.0% [76] 1264(3.2%) 0(0.0%) loop! .../various-cleanup/src/core/enode.rkt:190:2 custom-set-union [84] 100.0% ----------------------------------------------------------------- for-loop [59] 100.0% [77] 922(2.4%) 626(1.6%) foldr ....0/collects/racket/private/list.rkt:242:4 cp-2 [89] 32.1% ----------------------------------------------------------------- merge-egraph-nodes! [60] 100.0% [78] 916(2.3%) 0(0.0%) for-loop ...ious-cleanup/src/core/egraph.rkt:197:4 merge-egraph-nodes! [60] 100.0% ----------------------------------------------------------------- for-loop [59] 100.0% [79] 632(1.6%) 632(1.6%) filter ...0/collects/racket/private/list.rkt:256:2 ----------------------------------------------------------------- for-loop [59] 100.0% [80] 584(1.5%) 584(1.5%) cartesian-product ...ollects/racket/list.rkt:822:0 ----------------------------------------------------------------- ??? [64] 100.0% [81] 298(0.8%) 298(0.8%) ??? ...ects/racket/contract/private/guts.rkt:746:8 ----------------------------------------------------------------- update-leader! [71] 100.0% [82] 6644(17.0%) 0(0.0%) for-loop ...ious-cleanup/src/core/egraph.rkt:216:6 hash-update! [96] 54.7% for-loop [90] 45.3% ----------------------------------------------------------------- dedup-children! [73] 4.6% loop [83] 95.4% [83] 1938(5.0%) 1938(5.0%) loop ...racket-7.0/collects/racket/list.rkt:434:17 loop [83] 95.4% ----------------------------------------------------------------- loop! [76] 100.0% [84] 1264(3.2%) 354(0.9%) custom-set-union ...et/private/set-types.rkt:168:0 for-loop [92] 48.9% for-loop [95] 23.1% ----------------------------------------------------------------- for-loop [59] 45.3% loop [75] 54.7% [85] 658(1.7%) 0(0.0%) foldl ....0/collects/racket/private/list.rkt:229:4 merge2 [91] 100.0% ----------------------------------------------------------------- loop [75] 100.0% [86] 624(1.6%) 624(1.6%) curried ...-7.0/collects/racket/function.rkt:71:18 ----------------------------------------------------------------- update-leader! [71] 100.0% [87] 370(0.9%) 0(0.0%) custom-set-union! ...t/private/set-types.rkt:245:0 for-loop [93] 100.0% ----------------------------------------------------------------- dedup-children! [73] 10.0% loop [88] 90.0% [88] 308(0.8%) 0(0.0%) loop ...racket-7.0/collects/racket/list.rkt:417:34 loop [88] 90.0% loop [94] 10.0% ----------------------------------------------------------------- foldr [77] 100.0% [89] 296(0.8%) 296(0.8%) cp-2 .../racket-7.0/collects/racket/list.rkt:826:2 ----------------------------------------------------------------- for-loop [82] 100.0% [90] 3012(7.7%) 0(0.0%) for-loop ...ious-cleanup/src/core/egraph.rkt:217:8 hash-update! [96] 100.0% ----------------------------------------------------------------- foldl [85] 100.0% [91] 658(1.7%) 658(1.7%) merge2 ...various-cleanup/src/core/ematch.rkt:24:0 ----------------------------------------------------------------- custom-set-union [84] 100.0% [92] 618(1.6%) 0(0.0%) for-loop ...cts/racket/private/set-types.rkt:174:3 for-loop [97] 100.0% ----------------------------------------------------------------- custom-set-union! [87] 100.0% [93] 370(0.9%) 370(0.9%) for-loop ...cts/racket/private/set-types.rkt:253:4 ----------------------------------------------------------------- loop [88] 100.0% [94] 308(0.8%) 308(0.8%) loop (unknown source) ----------------------------------------------------------------- custom-set-union [84] 100.0% [95] 292(0.7%) 292(0.7%) for-loop ...cts/racket/private/set-types.rkt:152:2 ----------------------------------------------------------------- loop [17] 5.2% for-loop [90] 43.0% for-loop [82] 51.8% [96] 7012(17.9%) 368(0.9%) hash-update! ...cket/private/more-scheme.rkt:365:4 ??? [98] 94.8% ----------------------------------------------------------------- for-loop [92] 100.0% [97] 618(1.6%) 618(1.6%) for-loop ...cts/racket/private/set-types.rkt:178:5 ----------------------------------------------------------------- hash-update! [96] 100.0% [98] 6644(17.0%) 658(1.7%) ??? .../various-cleanup/src/core/egraph.rkt:219:24 for-loop [99] 90.1% ----------------------------------------------------------------- ??? [98] 100.0% [99] 5986(15.3%) 5060(12.9%) for-loop ...ous-cleanup/src/core/egraph.rkt:220:26 update-en-expr [100] 15.5% ----------------------------------------------------------------- for-loop [99] 100.0% [100] 926(2.4%) 926(2.4%) update-en-expr ...leanup/src/core/egraph.rkt:205:0 -----------------------------------------------------------------