Profiling results ----------------- Total cpu time observed: 111362ms (out of 114924ms) Number of samples taken: 246 (once every 453ms) ========================================================================= Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ========================================================================= ??? [33] 100.0% [1] 111362(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [7] 100.0% ------------------------------------------------------------------------- ??? [33] 100.0% [2] 19264(17.3%) 0(0.0%) run-improve43 ...ie/develop/src/mainloop.rkt:362:0 simplify! [8] 75.8% setup-prog!34 [9] 24.2% ------------------------------------------------------------------------- hash-ref! [55] 100.0% [3] 4078(3.7%) 0(0.0%) ??? ...tlies/herbie/develop/src/programs.rkt:190:5 loop [50] 100.0% ------------------------------------------------------------------------- ??? [33] 100.0% [4] 2098(1.9%) 0(0.0%) eval-const-expr .../develop/src/programs.rkt:150:0 eval-prog [20] 100.0% ------------------------------------------------------------------------- loop [68] 100.0% [5] 1580(1.4%) 0(0.0%) expand-id-application-form17 (unknown source) syntax-e/no-taint [10] 76.8% resolve+shift28 [42] 23.2% ------------------------------------------------------------------------- for-loop [62] 100.0% [6] 342(0.3%) 0(0.0%) syntax-e (unknown source) syntax-e/no-taint [10] 100.0% ------------------------------------------------------------------------- profile-thunk16 [1] 100.0% [7] 111362(100.0%) 0(0.0%) run ...et-7.0/share/pkgs/profile-lib/main.rkt:39:2 ??? [33] 100.0% ------------------------------------------------------------------------- run-improve43 [2] 100.0% [8] 14608(13.1%) 0(0.0%) simplify! ...herbie/develop/src/mainloop.rkt:239:0 simplify-batch6 [12] 100.0% ------------------------------------------------------------------------- run-improve43 [2] 100.0% [9] 4656(4.2%) 0(0.0%) setup-prog!34 ...bie/develop/src/mainloop.rkt:76:0 loop [11] 100.0% ------------------------------------------------------------------------- syntax-e [6] 22.0% expand-id-application-form17 [5] 78.0% [10] 1556(1.4%) 0(0.0%) syntax-e/no-taint (unknown source) loop [68] 100.0% ------------------------------------------------------------------------- setup-prog!34 [9] 4.8% ??? [33] 95.2% [11] 96056(86.3%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:320:2 loop [16] 64.3% loop [13] 33.1% make-exacts-walkup [17] 1.7% make-exacts-halfpoints35 [15] 1.0% ------------------------------------------------------------------------- simplify! [8] 100.0% [12] 14608(13.1%) 0(0.0%) simplify-batch6 ...elop/src/core/simplify.rkt:30:0 loop [14] 100.0% ------------------------------------------------------------------------- loop [11] 100.0% [13] 31752(28.5%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:275:2 make-exacts-walkup [17] 49.6% loop [16] 44.0% eval-prog [20] 6.4% ------------------------------------------------------------------------- simplify-batch6 [12] 100.0% [14] 14608(13.1%) 0(0.0%) loop .../herbie/develop/src/core/simplify.rkt:43:2 one-iter [18] 100.0% ------------------------------------------------------------------------- loop [11] 100.0% [15] 914(0.8%) 0(0.0%) make-exacts-halfpoints35 ...p/src/points.rkt:273:0 make-exacts-walkup [17] 100.0% ------------------------------------------------------------------------- loop [13] 18.4% loop [11] 81.6% [16] 75734(68.0%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:258:4 loop [50] 100.0% ------------------------------------------------------------------------- make-exacts-halfpoints35 [15] 5.0% loop [11] 8.8% loop [13] 86.2% [17] 18296(16.4%) 0(0.0%) make-exacts-walkup ...develop/src/points.rkt:255:0 loop [50] 79.7% eval-prog [20] 20.3% ------------------------------------------------------------------------- loop [14] 100.0% [18] 14608(13.1%) 0(0.0%) one-iter ...bie/develop/src/core/simplify.rkt:52:0 loop [19] 59.7% for-loop [21] 25.2% for-loop [22] 15.2% ------------------------------------------------------------------------- one-iter [18] 100.0% [19] 8714(7.8%) 0(0.0%) loop ...-7.0/collects/racket/private/map.rkt:74:19 set-precompute! [23] 100.0% ------------------------------------------------------------------------- loop [13] 25.9% eval-const-expr [4] 26.8% make-exacts-walkup [17] 47.4% [20] 7834(7.0%) 0(0.0%) eval-prog ...herbie/develop/src/programs.rkt:131:0 hash-ref! [55] 52.1% temp68_2 [24] 36.0% eval [26] 7.6% loop [68] 4.4% ------------------------------------------------------------------------- one-iter [18] 100.0% [21] 3678(3.3%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:103:2 update-leader! [40] 91.4% for-loop [27] 8.6% ------------------------------------------------------------------------- for-loop [22] 43.3% one-iter [18] 56.7% [22] 2216(2.0%) 598(0.5%) for-loop ...ie/develop/src/core/simplify.rkt:63:12 for-loop [22] 43.3% match-e [25] 36.5% ------------------------------------------------------------------------- loop [19] 100.0% [23] 8714(7.8%) 0(0.0%) set-precompute! ...lop/src/core/simplify.rkt:122:0 for-loop [28] 100.0% ------------------------------------------------------------------------- eval-prog [20] 100.0% [24] 2822(2.5%) 0(0.0%) temp68_2 (unknown source) compile16 [29] 100.0% ------------------------------------------------------------------------- for-loop [37] 20.8% for-loop [22] 79.2% [25] 1618(1.5%) 332(0.3%) match-e ...herbie/develop/src/core/ematch.rkt:39:0 for-loop [30] 58.7% ??? [36] 11.3% variable? [32] 9.5% ------------------------------------------------------------------------- eval-prog [20] 100.0% [26] 592(0.5%) 0(0.0%) eval (unknown source) namespace-syntax-introduce3 [31]100.0% ------------------------------------------------------------------------- for-loop [21] 100.0% [27] 316(0.3%) 0(0.0%) for-loop ...bie/develop/src/core/simplify.rkt:86:4 merge-egraph-nodes! [34] 100.0% ------------------------------------------------------------------------- set-precompute! [23] 100.0% [28] 8714(7.8%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:124:2 ??? [33] 48.9% merge-egraph-nodes! [34] 39.9% loop! [56] 7.5% compose [38] 3.8% ------------------------------------------------------------------------- temp68_2 [24] 100.0% [29] 2822(2.5%) 0(0.0%) compile16 (unknown source) temp74_0 [35] 100.0% ------------------------------------------------------------------------- match-e [25] 100.0% [30] 1286(1.2%) 614(0.6%) for-loop ...rbie/develop/src/core/ematch.rkt:52:11 for-loop [37] 52.3% ------------------------------------------------------------------------- eval [26] 100.0% [31] 592(0.5%) 294(0.3%) namespace-syntax-introduce3 (unknown source) free-identifier=? [39] 50.3% ------------------------------------------------------------------------- match-e [25] 100.0% [32] 306(0.3%) 0(0.0%) variable? .../develop/src/syntax/syntax.rkt:1159:0 ??? [36] 100.0% ------------------------------------------------------------------------- for-loop [28] 1.3% run [7] 49.4% [33] 111362(100.0%) 654(0.6%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 49.4% loop [11] 41.0% run-improve43 [2] 8.0% eval-const-expr [4] 0.6% ??? [59] 0.5% errors [43] 0.2% oracle-error [44] 0.1% ------------------------------------------------------------------------- for-loop [27] 4.2% merge-egraph-nodes! [34] 8.8% for-loop [28] 87.0% [34] 3790(3.4%) 0(0.0%) merge-egraph-nodes! ...p/src/core/egraph.rkt:144:0 loop! [56] 46.0% update-leader! [40] 45.2% merge-egraph-nodes! [34] 8.8% ------------------------------------------------------------------------- compile16 [29] 100.0% [35] 2822(2.5%) 0(0.0%) temp74_0 (unknown source) expand-single [41] 100.0% ------------------------------------------------------------------------- variable? [32] 45.5% match-e [25] 54.5% [36] 672(0.6%) 672(0.6%) ??? ...contract/private/arrow-val-first.rkt:388:18 ------------------------------------------------------------------------- for-loop [30] 100.0% [37] 672(0.6%) 0(0.0%) for-loop ...rbie/develop/src/core/ematch.rkt:58:37 match-e [25] 100.0% ------------------------------------------------------------------------- for-loop [28] 100.0% [38] 328(0.3%) 328(0.3%) compose ...collects/racket/private/list.rkt:385:12 ------------------------------------------------------------------------- namespace-syntax-introduce3 [31]100.0% [39] 298(0.3%) 0(0.0%) free-identifier=? (unknown source) resolve+shift28 [42] 100.0% ------------------------------------------------------------------------- merge-egraph-nodes! [34] 35.8% for-loop [21] 64.2% [40] 5234(4.7%) 0(0.0%) update-leader! ...evelop/src/core/egraph.rkt:212:0 for-loop [45] 100.0% ------------------------------------------------------------------------- temp74_0 [35] 100.0% [41] 2822(2.5%) 0(0.0%) expand-single (unknown source) expand-capturing-lifts [46] 100.0% ------------------------------------------------------------------------- free-identifier=? [39] 44.9% expand-id-application-form17 [5] 55.1% [42] 664(0.6%) 298(0.3%) resolve+shift28 (unknown source) fallback-loop [48] 55.1% ------------------------------------------------------------------------- ??? [33] 100.0% [43] 394(0.4%) 0(0.0%) errors ...lies/herbie/develop/src/points.rkt:232:0 for-loop [47] 100.0% ------------------------------------------------------------------------- ??? [33] 100.0% [44] 304(0.3%) 0(0.0%) oracle-error ...erbie/develop/src/points.rkt:216:0 for-loop [49] 100.0% ------------------------------------------------------------------------- update-leader! [40] 100.0% [45] 5234(4.7%) 296(0.3%) for-loop ...rbie/develop/src/core/egraph.rkt:217:6 for-loop [51] 58.4% hash-update! [54] 36.0% ------------------------------------------------------------------------- loop [68] 10.8% expand-single [41] 89.2% [46] 3164(2.8%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [52] 89.2% loop [68] 10.8% ------------------------------------------------------------------------- errors [43] 100.0% [47] 394(0.4%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:235:4 ulp-difference [58] 100.0% ------------------------------------------------------------------------- resolve+shift28 [42] 100.0% [48] 366(0.3%) 0(0.0%) fallback-loop (unknown source) for-loop [62] 100.0% ------------------------------------------------------------------------- oracle-error [44] 100.0% [49] 304(0.3%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:217:2 loop [50] 100.0% ------------------------------------------------------------------------- loop! [56] 0.1% make-exacts-walkup [17] 0.2% for-loop [49] 0.3% loop [16] 1.8% ??? [3] 4.2% loop [50] 93.4% [50] 96016(86.2%) 0(0.0%) loop ...-7.0/collects/racket/private/map.rkt:40:19 loop [50] 93.4% hash-ref! [55] 4.2% composed [53] 2.0% ulp-difference [58] 0.3% loop! [56] 0.1% ??? [75] 0.0% ------------------------------------------------------------------------- for-loop [45] 100.0% [51] 3056(2.7%) 0(0.0%) for-loop ...rbie/develop/src/core/egraph.rkt:218:8 hash-update! [54] 100.0% ------------------------------------------------------------------------- expand-capturing-lifts [46] 100.0% [52] 2822(2.5%) 0(0.0%) lambda-clause-expander (unknown source) finish-bodys [57] 89.5% loop [68] 10.5% ------------------------------------------------------------------------- loop [50] 100.0% [53] 89880(80.7%) 0(0.0%) composed ...ollects/racket/private/list.rkt:321:16 ??? [59] 100.0% ------------------------------------------------------------------------- for-loop [45] 38.1% for-loop [51] 61.9% [54] 4938(4.4%) 0(0.0%) hash-update! ...cket/private/more-scheme.rkt:365:4 ??? [60] 100.0% ------------------------------------------------------------------------- eval-prog [20] 18.3% loop [50] 81.7% [55] 4078(3.7%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 ??? [3] 81.7% bigfloat-hash [61] 18.3% ------------------------------------------------------------------------- for-loop [28] 19.0% loop [50] 25.6% merge-egraph-nodes! [34] 55.5% [56] 2568(2.3%) 298(0.3%) loop! ...s/herbie/develop/src/core/enode.rkt:190:2 custom-set-union [63] 55.4% loop [50] 25.6% set [64] 13.2% ------------------------------------------------------------------------- lambda-clause-expander [52] 36.2% for-loop [62] 63.8% [57] 2526(2.3%) 0(0.0%) finish-bodys (unknown source) for-loop [62] 100.0% ------------------------------------------------------------------------- loop [50] 43.6% for-loop [47] 56.4% [58] 698(0.6%) 0(0.0%) ulp-difference ...erbie/develop/src/float.rkt:13:0 return/no-unsupplied [85] 56.4% ??? [65] 43.6% ------------------------------------------------------------------------- ??? [33] 1.7% composed [53] 98.3% [59] 91390(82.1%) 0(0.0%) ??? ...tlies/herbie/develop/src/programs.rkt:147:2 ??? [66] 100.0% ------------------------------------------------------------------------- hash-update! [54] 100.0% [60] 4938(4.4%) 296(0.3%) ??? ...s/herbie/develop/src/core/egraph.rkt:220:24 for-loop [67] 94.0% ------------------------------------------------------------------------- hash-ref! [55] 100.0% [61] 4078(3.7%) 0(0.0%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:167:0 bfcanonicalize [69] 62.2% sig+exp->bigfloat [70] 37.8% ------------------------------------------------------------------------- fallback-loop [48] 0.5% for-loop [62] 11.3% finish-bodys [57] 88.2% [62] 2526(2.3%) 674(0.6%) for-loop (unknown source) finish-bodys [57] 59.7% loop [68] 19.6% for-loop [62] 11.3% add-local-binding!37 [72] 2.3% syntax-e [6] 0.5% ------------------------------------------------------------------------- loop! [56] 100.0% [63] 1592(1.4%) 338(0.3%) custom-set-union ...et/private/set-types.rkt:168:0 for-loop [71] 78.8% ------------------------------------------------------------------------- loop! [56] 100.0% [64] 678(0.6%) 678(0.6%) set ...collects/racket/private/set-types.rkt:981:0 ------------------------------------------------------------------------- ulp-difference [58] 100.0% [65] 304(0.3%) 304(0.3%) ??? ...s/racket/contract/private/arr-i.rkt:1252:29 ------------------------------------------------------------------------- ??? [59] 100.0% [66] 91390(82.1%) 1912(1.7%) ??? ...t-7.0/collects/racket/private/kw.rkt:592:14 ??? [73] 97.5% ??? [75] 0.4% ------------------------------------------------------------------------- ??? [60] 100.0% [67] 4642(4.2%) 4642(4.2%) for-loop ...bie/develop/src/core/egraph.rkt:221:26 ------------------------------------------------------------------------- expand-capturing-lifts [46] 6.7% eval-prog [20] 6.7% lambda-clause-expander [52] 11.6% syntax-e/no-taint [10] 37.1% for-loop [62] 38.0% [68] 2560(2.3%) 638(0.6%) loop (unknown source) expand-id-application-form17 [5] 38.0% gf [74] 37.1% expand-capturing-lifts [46] 6.7% ------------------------------------------------------------------------- bigfloat-hash [61] 100.0% [69] 2538(2.3%) 2166(1.9%) bfcanonicalize .../private/bigfloat/mpfr.rkt:157:0 bigfloat->sig+exp [77] 14.7% ------------------------------------------------------------------------- bigfloat-hash [61] 100.0% [70] 1540(1.4%) 0(0.0%) sig+exp->bigfloat ...ivate/bigfloat/mpfr.rkt:387:0 new-mpfr [91] 77.5% integer->mpz [78] 22.5% ------------------------------------------------------------------------- custom-set-union [63] 100.0% [71] 1254(1.1%) 624(0.6%) for-loop ...cts/racket/private/set-types.rkt:174:3 for-loop [76] 50.2% ------------------------------------------------------------------------- for-loop [62] 100.0% [72] 296(0.3%) 296(0.3%) add-local-binding!37 (unknown source) ------------------------------------------------------------------------- ??? [66] 100.0% [73] 89128(80.0%) 296(0.3%) ??? ...tract/private/arrow-higher-order.rkt:361:33 bf-complex-mult [79] 94.1% bf-complex-add [80] 5.5% ------------------------------------------------------------------------- loop [68] 100.0% [74] 1556(1.4%) 0(0.0%) gf (unknown source) propagation-apply [81] 100.0% ------------------------------------------------------------------------- ??? [66] 44.3% loop [50] 55.7% [75] 790(0.7%) 350(0.3%) ??? ...tract/private/arrow-higher-order.rkt:357:33 ->flonum [82] 55.7% ------------------------------------------------------------------------- for-loop [71] 100.0% [76] 630(0.6%) 630(0.6%) for-loop ...cts/racket/private/set-types.rkt:178:5 ------------------------------------------------------------------------- bfcanonicalize [69] 100.0% [77] 372(0.3%) 372(0.3%) bigfloat->sig+exp ...ivate/bigfloat/mpfr.rkt:372:0 ------------------------------------------------------------------------- sig+exp->bigfloat [70] 100.0% [78] 346(0.3%) 346(0.3%) integer->mpz ...math/private/bigfloat/gmp.rkt:87:0 ------------------------------------------------------------------------- ??? [73] 100.0% [79] 83900(75.3%) 4478(4.0%) bf-complex-mult ...develop/src/bigcomplex.rkt:30:0 bf* [83] 50.2% ??? [86] 40.1% ...higher-order.rkt:361:33 [84] 4.4% ------------------------------------------------------------------------- ??? [73] 100.0% [80] 4932(4.4%) 0(0.0%) bf-complex-add .../develop/src/bigcomplex.rkt:23:0 ??? [86] 100.0% ------------------------------------------------------------------------- gf [74] 100.0% [81] 1556(1.4%) 1556(1.4%) propagation-apply (unknown source) ------------------------------------------------------------------------- ??? [75] 100.0% [82] 440(0.4%) 0(0.0%) ->flonum ...s/herbie/develop/src/programs.rkt:37:0 return/no-unsupplied [85] 100.0% ------------------------------------------------------------------------- bf-complex-mult [79] 100.0% [83] 42092(37.8%) 366(0.3%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [86] 99.1% ------------------------------------------------------------------------- bf-complex-mult [79] 100.0% [84] 3674(3.3%) 1090(1.0%) ...higher-order.rkt:361:33 (unknown source) ??? [87] 70.3% ------------------------------------------------------------------------- ulp-difference [58] 47.2% ->flonum [82] 52.8% [85] 834(0.7%) 394(0.4%) return/no-unsupplied ...ct/private/arr-i.rkt:534:0 filter [88] 52.8% ------------------------------------------------------------------------- bf-complex-add [80] 6.1% bf-complex-mult [79] 41.9% bf* [83] 52.0% [86] 80314(72.1%) 2654(2.4%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [89] 87.3% bfneg [90] 9.4% ------------------------------------------------------------------------- ...higher-order.rkt:361:33 [84] 100.0% [87] 2584(2.3%) 2584(2.3%) ??? ...cts/racket/contract/private/list.rkt:209:10 ------------------------------------------------------------------------- return/no-unsupplied [85] 100.0% [88] 440(0.4%) 440(0.4%) filter ...0/collects/racket/private/list.rkt:256:2 ------------------------------------------------------------------------- ??? [86] 100.0% [89] 70092(62.9%) 4098(3.7%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [91] 62.2% ??? [92] 31.9% ------------------------------------------------------------------------- ??? [86] 100.0% [90] 7568(6.8%) 442(0.4%) bfneg ...-lib/math/private/bigfloat/mpfr.rkt:618:4 new-mpfr [91] 68.3% ??? [92] 25.9% ------------------------------------------------------------------------- sig+exp->bigfloat [70] 2.4% bfneg [90] 10.3% bfmul [89] 87.3% [91] 49972(44.9%) 49972(44.9%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ------------------------------------------------------------------------- bfneg [90] 8.1% bfmul [89] 91.9% [92] 24342(21.9%) 24342(21.9%) ??? /opt/racket-7.0/collects/ffi/unsafe.rkt:898:4 -------------------------------------------------------------------------