Profiling results ----------------- Total cpu time observed: 23042ms (out of 23704ms) Number of samples taken: 65 (once every 354ms) ====================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ====================================================================== ??? [7] 100.0% [1] 23042(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [4] 100.0% ---------------------------------------------------------------------- ??? [7] 100.0% [2] 608(2.6%) 0(0.0%) errors ...lies/herbie/develop/src/points.rkt:223:0 for-loop [5] 100.0% ---------------------------------------------------------------------- ??? [7] 100.0% [3] 298(1.3%) 0(0.0%) oracle-error ...erbie/develop/src/points.rkt:207:0 for-loop [6] 100.0% ---------------------------------------------------------------------- profile-thunk16 [1] 100.0% [4] 23042(100.0%) 0(0.0%) run ...et-7.0/share/pkgs/profile-lib/main.rkt:39:2 ??? [7] 100.0% ---------------------------------------------------------------------- errors [2] 100.0% [5] 608(2.6%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:225:2 ??? [24] 100.0% ---------------------------------------------------------------------- oracle-error [3] 100.0% [6] 298(1.3%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:208:2 loop [15] 100.0% ---------------------------------------------------------------------- run [4] 50.0% [7] 23042(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 50.0% prepare-points-intervals [10] 44.7% run-improve40 [8] 2.0% errors [2] 1.3% errors-score [9] 0.7% oracle-error [3] 0.6% loop [15] 0.6% ---------------------------------------------------------------------- ??? [7] 100.0% [8] 908(3.9%) 0(0.0%) run-improve40 ...ie/develop/src/mainloop.rkt:340:0 simplify! [11] 70.9% prepare-points-intervals [10] 29.1% ---------------------------------------------------------------------- ??? [7] 100.0% [9] 310(1.3%) 310(1.3%) errors-score ...erbie/develop/src/points.rkt:215:0 ---------------------------------------------------------------------- run-improve40 [8] 1.3% ??? [7] 98.7% [10] 20886(90.6%) 0(0.0%) prepare-points-intervals ...p/src/points.rkt:122:0 loop [12] 100.0% ---------------------------------------------------------------------- run-improve40 [8] 100.0% [11] 644(2.8%) 0(0.0%) simplify! ...herbie/develop/src/mainloop.rkt:217:0 ??? [13] 100.0% ---------------------------------------------------------------------- prepare-points-intervals [10] 100.0% [12] 20886(90.6%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:138:4 loop [14] 98.6% loop [15] 1.4% ---------------------------------------------------------------------- simplify! [11] 100.0% [13] 644(2.8%) 0(0.0%) ??? ...s/herbie/develop/src/core/simplify.rkt:29:0 loop [16] 100.0% ---------------------------------------------------------------------- loop [12] 100.0% [14] 20598(89.4%) 606(2.6%) loop ...htlies/herbie/develop/src/points.rkt:103:2 ??? [17] 89.6% ??? [24] 7.5% ---------------------------------------------------------------------- loop [12] 32.7% ??? [7] 33.6% for-loop [6] 33.8% [15] 882(3.8%) 0(0.0%) loop ...-7.0/collects/racket/private/map.rkt:40:19 ??? [17] 33.8% eval-prog [19] 33.6% ??? [24] 32.7% ---------------------------------------------------------------------- ??? [13] 100.0% [16] 644(2.8%) 0(0.0%) loop .../herbie/develop/src/core/simplify.rkt:44:2 one-iter [18] 100.0% ---------------------------------------------------------------------- loop [15] 1.6% loop [14] 98.4% [17] 18754(81.4%) 0(0.0%) ??? ...tlies/herbie/develop/src/programs.rkt:146:2 ??? [20] 81.7% ??? [23] 18.3% ---------------------------------------------------------------------- loop [16] 100.0% [18] 644(2.8%) 0(0.0%) one-iter ...ie/develop/src/core/simplify.rkt:100:0 for-loop [21] 100.0% ---------------------------------------------------------------------- loop [15] 100.0% [19] 296(1.3%) 0(0.0%) eval-prog ...herbie/develop/src/programs.rkt:130:0 temp68_2 [22] 100.0% ---------------------------------------------------------------------- ??? [17] 100.0% [20] 15320(66.5%) 586(2.5%) ??? ...t-7.0/collects/racket/private/kw.rkt:592:14 ??? [23] 55.9% ??? [24] 38.4% ??? [26] 1.9% ---------------------------------------------------------------------- one-iter [18] 100.0% [21] 644(2.8%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:101:2 apply-match [25] 100.0% ---------------------------------------------------------------------- eval-prog [19] 100.0% [22] 296(1.3%) 0(0.0%) temp68_2 (unknown source) compile16 [27] 100.0% ---------------------------------------------------------------------- fold-binary-pred [46] 6.8% ??? [17] 27.8% ??? [20] 65.4% [23] 12362(53.6%) 1944(8.4%) ??? ...tract/private/arrow-higher-order.rkt:361:33 ival-div [28] 45.9% mk-ival [30] 25.1% ival-sub [32] 10.2% ival-sqrt [33] 6.9% ---------------------------------------------------------------------- loop [15] 3.5% for-loop [5] 7.3% loop [14] 18.5% ??? [20] 70.7% [24] 8310(36.1%) 288(1.2%) ??? ...tract/private/arrow-higher-order.rkt:357:33 ival-mult [29] 70.7% ->flonum [31] 25.8% ---------------------------------------------------------------------- for-loop [21] 100.0% [25] 644(2.8%) 0(0.0%) apply-match .../develop/src/core/simplify.rkt:73:0 for-loop [34] 100.0% ---------------------------------------------------------------------- ??? [20] 100.0% [26] 298(1.3%) 298(1.3%) ??? ...herbie/develop/src/syntax/syntax.rkt:317:17 ---------------------------------------------------------------------- temp68_2 [22] 100.0% [27] 296(1.3%) 0(0.0%) compile16 (unknown source) temp74_0 [35] 100.0% ---------------------------------------------------------------------- ??? [23] 100.0% [28] 5972(25.9%) 922(4.0%) ival-div ...rbie/develop/src/biginterval.rkt:131:0 ??? [45] 52.7% force [39] 16.3% classify-ival [38] 10.6% fold-binary-pred [46] 4.9% ---------------------------------------------------------------------- ??? [24] 100.0% [29] 5878(25.5%) 0(0.0%) ival-mult ...rbie/develop/src/biginterval.rkt:95:0 bf* [36] 70.7% classify-ival [38] 29.3% ---------------------------------------------------------------------- ??? [23] 100.0% [30] 3098(13.4%) 594(2.6%) mk-ival ...herbie/develop/src/biginterval.rkt:53:0 bf [37] 80.8% ---------------------------------------------------------------------- ??? [24] 100.0% [31] 2144(9.3%) 1242(5.4%) ->flonum ...s/herbie/develop/src/programs.rkt:38:0 ??? [57] 28.1% ??? [43] 14.0% ---------------------------------------------------------------------- ??? [23] 100.0% [32] 1264(5.5%) 0(0.0%) ival-sub ...erbie/develop/src/biginterval.rkt:83:0 ??? [45] 75.2% ...higher-order.rkt:361:33 [41] 24.8% ---------------------------------------------------------------------- ??? [23] 100.0% [33] 1020(4.4%) 0(0.0%) ival-sqrt ...bie/develop/src/biginterval.rkt:181:0 ??? [45] 66.5% fold-binary-pred [46] 33.5% ---------------------------------------------------------------------- apply-match [25] 100.0% [34] 644(2.8%) 0(0.0%) for-loop ...bie/develop/src/core/simplify.rkt:87:2 dedup-children! [40] 51.9% mk-enode! [42] 48.1% ---------------------------------------------------------------------- compile16 [27] 100.0% [35] 296(1.3%) 0(0.0%) temp74_0 (unknown source) expand-single [44] 100.0% ---------------------------------------------------------------------- ival-mult [29] 100.0% [36] 4156(18.0%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [45] 100.0% ---------------------------------------------------------------------- mk-ival [30] 100.0% [37] 2504(10.9%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [56] 85.1% ??? [57] 14.9% ---------------------------------------------------------------------- ival-div [28] 26.8% ival-mult [29] 73.2% [38] 2354(10.2%) 674(2.9%) classify-ival ...develop/src/biginterval.rkt:300:0 ...higher-order.rkt:357:33 [47] 42.9% fold-binary-pred [46] 28.5% ---------------------------------------------------------------------- ival-div [28] 100.0% [39] 976(4.2%) 976(4.2%) force ...collects/racket/private/promise.rkt:109:0 ---------------------------------------------------------------------- for-loop [34] 100.0% [40] 334(1.4%) 0(0.0%) dedup-children! ...evelop/src/core/enode.rkt:199:0 loop [48] 100.0% ---------------------------------------------------------------------- ival-sub [32] 100.0% [41] 314(1.4%) 0(0.0%) ...higher-order.rkt:361:33 (unknown source) ??? [52] 100.0% ---------------------------------------------------------------------- for-loop [34] 100.0% [42] 310(1.3%) 0(0.0%) mk-enode! ...rbie/develop/src/core/egraph.rkt:99:0 new-enode [49] 100.0% ---------------------------------------------------------------------- ->flonum [31] 100.0% [43] 300(1.3%) 300(1.3%) ??? ...ects/racket/contract/private/guts.rkt:746:8 ---------------------------------------------------------------------- temp74_0 [35] 100.0% [44] 296(1.3%) 0(0.0%) expand-single (unknown source) expand-capturing-lifts [50] 100.0% ---------------------------------------------------------------------- ival-sqrt [33] 7.6% ival-sub [32] 10.6% ival-div [28] 35.2% bf* [36] 46.5% [45] 8932(38.8%) 974(4.2%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfdiv [51] 81.5% bfsqrt [53] 7.6% ---------------------------------------------------------------------- ival-div [28] 22.5% ival-sqrt [33] 26.2% classify-ival [38] 51.3% [46] 1306(5.7%) 0(0.0%) fold-binary-pred ...gfloat/bigfloat-mpfr.rkt:117:0 ??? [23] 100.0% ---------------------------------------------------------------------- classify-ival [38] 100.0% [47] 1010(4.4%) 332(1.4%) ...higher-order.rkt:357:33 (unknown source) ??? [52] 67.1% ---------------------------------------------------------------------- dedup-children! [40] 25.0% loop [48] 75.0% [48] 334(1.4%) 0(0.0%) loop ...racket-7.0/collects/racket/list.rkt:417:34 loop [48] 75.0% loop [62] 25.0% ---------------------------------------------------------------------- mk-enode! [42] 100.0% [49] 310(1.3%) 0(0.0%) new-enode ...erbie/develop/src/core/enode.rkt:96:0 mutable-set [54] 100.0% ---------------------------------------------------------------------- expand-single [44] 100.0% [50] 296(1.3%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [55] 100.0% ---------------------------------------------------------------------- ??? [45] 100.0% [51] 7280(31.6%) 308(1.3%) bfdiv ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [56] 71.6% ??? [57] 24.2% ---------------------------------------------------------------------- ...higher-order.rkt:361:33 [41] 31.7% ...higher-order.rkt:357:33 [47] 68.3% [52] 992(4.3%) 992(4.3%) ??? ...cts/racket/contract/private/list.rkt:209:10 ---------------------------------------------------------------------- ??? [45] 100.0% [53] 678(2.9%) 0(0.0%) bfsqrt ...lib/math/private/bigfloat/mpfr.rkt:618:4 new-mpfr [56] 100.0% ---------------------------------------------------------------------- new-enode [49] 100.0% [54] 310(1.3%) 310(1.3%) mutable-set .../racket/private/set-types.rkt:999:0 ---------------------------------------------------------------------- expand-capturing-lifts [50] 100.0% [55] 296(1.3%) 0(0.0%) lambda-clause-expander (unknown source) finish-bodys [58] 100.0% ---------------------------------------------------------------------- bfsqrt [53] 8.5% bf [37] 26.6% bfdiv [51] 65.0% [56] 8020(34.8%) 8020(34.8%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ---------------------------------------------------------------------- bf [37] 13.6% ->flonum [31] 22.0% bfdiv [51] 64.4% [57] 2736(11.9%) 2736(11.9%) ??? /opt/racket-7.0/collects/ffi/unsafe.rkt:898:4 ---------------------------------------------------------------------- lambda-clause-expander [55] 100.0% [58] 296(1.3%) 0(0.0%) finish-bodys (unknown source) for-loop [59] 100.0% ---------------------------------------------------------------------- for-loop [59] 50.0% finish-bodys [58] 50.0% [59] 296(1.3%) 0(0.0%) for-loop (unknown source) for-loop [59] 50.0% syntax-e [60] 50.0% ---------------------------------------------------------------------- for-loop [59] 100.0% [60] 296(1.3%) 0(0.0%) syntax-e (unknown source) syntax-e/no-taint [61] 100.0% ---------------------------------------------------------------------- syntax-e [60] 100.0% [61] 296(1.3%) 0(0.0%) syntax-e/no-taint (unknown source) loop [62] 100.0% ---------------------------------------------------------------------- syntax-e/no-taint [61] 47.0% loop [48] 53.0% [62] 630(2.7%) 334(1.4%) loop (unknown source) gf [63] 47.0% ---------------------------------------------------------------------- loop [62] 100.0% [63] 296(1.3%) 0(0.0%) gf (unknown source) cache-or-reuse-set [64] 100.0% ---------------------------------------------------------------------- gf [63] 100.0% [64] 296(1.3%) 296(1.3%) cache-or-reuse-set (unknown source) ----------------------------------------------------------------------