Profiling results ----------------- Total cpu time observed: 7216ms (out of 7564ms) Number of samples taken: 22 (once every 328ms) ===================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ===================================================================== ??? [16] 100.0% [1] 7216(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [5] 100.0% --------------------------------------------------------------------- ??? [16] 100.0% [2] 604(8.4%) 0(0.0%) run-improve40 ...ie/develop/src/mainloop.rkt:340:0 simplify! [6] 100.0% --------------------------------------------------------------------- ??? [16] 100.0% [3] 320(4.4%) 0(0.0%) eval-const-expr .../develop/src/programs.rkt:149:0 eval-prog [23] 100.0% --------------------------------------------------------------------- match-e [17] 100.0% [4] 284(3.9%) 0(0.0%) for-loop ...rbie/develop/src/core/ematch.rkt:52:11 for-loop [7] 100.0% --------------------------------------------------------------------- profile-thunk16 [1] 100.0% [5] 7216(100.0%) 0(0.0%) run ...et-7.0/share/pkgs/profile-lib/main.rkt:39:2 ??? [16] 100.0% --------------------------------------------------------------------- run-improve40 [2] 100.0% [6] 604(8.4%) 0(0.0%) simplify! ...herbie/develop/src/mainloop.rkt:217:0 ??? [8] 100.0% --------------------------------------------------------------------- for-loop [4] 100.0% [7] 284(3.9%) 0(0.0%) for-loop ...rbie/develop/src/core/ematch.rkt:58:37 match-e [17] 100.0% --------------------------------------------------------------------- simplify! [6] 100.0% [8] 604(8.4%) 0(0.0%) ??? ...s/herbie/develop/src/core/simplify.rkt:29:0 loop [9] 100.0% --------------------------------------------------------------------- ??? [8] 100.0% [9] 604(8.4%) 0(0.0%) loop .../herbie/develop/src/core/simplify.rkt:44:2 loop [10] 53.0% one-iter [11] 47.0% --------------------------------------------------------------------- loop [9] 100.0% [10] 320(4.4%) 0(0.0%) loop ...-7.0/collects/racket/private/map.rkt:74:19 set-precompute! [12] 100.0% --------------------------------------------------------------------- loop [9] 100.0% [11] 284(3.9%) 0(0.0%) one-iter ...ie/develop/src/core/simplify.rkt:100:0 for-loop [13] 100.0% --------------------------------------------------------------------- loop [10] 100.0% [12] 320(4.4%) 0(0.0%) set-precompute! ...lop/src/core/simplify.rkt:105:0 for-loop [14] 100.0% --------------------------------------------------------------------- one-iter [11] 100.0% [13] 284(3.9%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:101:2 apply-match [15] 100.0% --------------------------------------------------------------------- set-precompute! [12] 100.0% [14] 320(4.4%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:108:2 ??? [16] 100.0% --------------------------------------------------------------------- for-loop [13] 100.0% [15] 284(3.9%) 0(0.0%) apply-match .../develop/src/core/simplify.rkt:73:0 match-e [17] 100.0% --------------------------------------------------------------------- for-loop [14] 1.5% run [5] 49.3% [16] 7216(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 49.3% prepare-points-intervals [18] 41.1% run-improve40 [2] 3.4% errors-score [19] 2.5% errors [20] 2.2% eval-const-expr [3] 1.5% --------------------------------------------------------------------- for-loop [7] 50.0% apply-match [15] 50.0% [17] 284(3.9%) 0(0.0%) match-e ...herbie/develop/src/core/ematch.rkt:39:0 for-loop [4] 50.0% variable? [21] 50.0% --------------------------------------------------------------------- ??? [16] 100.0% [18] 5936(82.3%) 0(0.0%) prepare-points-intervals ...p/src/points.rkt:133:0 loop [22] 100.0% --------------------------------------------------------------------- ??? [16] 100.0% [19] 360(5.0%) 0(0.0%) errors-score ...erbie/develop/src/points.rkt:220:0 loop [24] 100.0% --------------------------------------------------------------------- ??? [16] 100.0% [20] 316(4.4%) 0(0.0%) errors ...lies/herbie/develop/src/points.rkt:228:0 eval-prog [23] 100.0% --------------------------------------------------------------------- match-e [17] 100.0% [21] 284(3.9%) 0(0.0%) variable? .../develop/src/syntax/syntax.rkt:1159:0 ??? [28] 100.0% --------------------------------------------------------------------- prepare-points-intervals [18] 100.0% [22] 5936(82.3%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:145:4 loop [25] 100.0% --------------------------------------------------------------------- errors [20] 49.7% eval-const-expr [3] 50.3% [23] 636(8.8%) 0(0.0%) eval-prog ...herbie/develop/src/programs.rkt:130:0 temp68_2 [26] 100.0% --------------------------------------------------------------------- errors-score [19] 0.1% loop [24] 99.9% [24] 360(5.0%) 360(5.0%) loop ...-7.0/collects/racket/private/map.rkt:40:19 loop [24] 99.9% --------------------------------------------------------------------- loop [22] 100.0% [25] 5936(82.3%) 336(4.7%) loop ...htlies/herbie/develop/src/points.rkt:104:2 ??? [27] 83.0% ??? [28] 11.4% --------------------------------------------------------------------- eval-prog [23] 100.0% [26] 636(8.8%) 0(0.0%) temp68_2 (unknown source) compile16 [29] 100.0% --------------------------------------------------------------------- loop [25] 100.0% [27] 4926(68.3%) 0(0.0%) ??? ...tlies/herbie/develop/src/programs.rkt:146:2 ??? [30] 100.0% --------------------------------------------------------------------- variable? [21] 29.6% loop [25] 70.4% [28] 958(13.3%) 958(13.3%) ??? ...contract/private/arrow-val-first.rkt:388:18 --------------------------------------------------------------------- temp68_2 [26] 100.0% [29] 636(8.8%) 0(0.0%) compile16 (unknown source) temp74_0 [31] 100.0% --------------------------------------------------------------------- ??? [27] 100.0% [30] 4926(68.3%) 0(0.0%) ??? ...t-7.0/collects/racket/private/kw.rkt:592:14 ??? [32] 58.7% ??? [33] 41.3% --------------------------------------------------------------------- compile16 [29] 100.0% [31] 636(8.8%) 0(0.0%) temp74_0 (unknown source) expand-single [34] 100.0% --------------------------------------------------------------------- ??? [30] 100.0% [32] 2894(40.1%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:361:33 ival-add [36] 56.8% ival-div [37] 43.2% --------------------------------------------------------------------- ??? [30] 100.0% [33] 2032(28.2%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:357:33 ival-mult [35] 100.0% --------------------------------------------------------------------- temp74_0 [31] 100.0% [34] 636(8.8%) 0(0.0%) expand-single (unknown source) expand-capturing-lifts [38] 100.0% --------------------------------------------------------------------- ??? [33] 100.0% [35] 2032(28.2%) 298(4.1%) ival-mult ...bie/develop/src/biginterval.rkt:105:0 bf* [40] 49.3% classify-ival [41] 18.9% ...higher-order.rkt:361:33 [39] 17.1% --------------------------------------------------------------------- ??? [32] 100.0% [36] 1644(22.8%) 0(0.0%) ival-add ...erbie/develop/src/biginterval.rkt:87:0 ...higher-order.rkt:361:33 [39] 58.2% ??? [43] 41.8% --------------------------------------------------------------------- ??? [32] 100.0% [37] 1250(17.3%) 0(0.0%) ival-div ...rbie/develop/src/biginterval.rkt:141:0 ??? [43] 74.9% classify-ival [41] 25.1% --------------------------------------------------------------------- expand-single [34] 100.0% [38] 636(8.8%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [42] 100.0% --------------------------------------------------------------------- ival-mult [35] 26.7% ival-add [36] 73.3% [39] 1304(18.1%) 956(13.2%) ...higher-order.rkt:361:33 (unknown source) ??? [45] 26.7% --------------------------------------------------------------------- ival-mult [35] 100.0% [40] 1002(13.9%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [43] 100.0% --------------------------------------------------------------------- ival-div [37] 45.0% ival-mult [35] 55.0% [41] 698(9.7%) 0(0.0%) classify-ival ...develop/src/biginterval.rkt:310:0 force [44] 100.0% --------------------------------------------------------------------- expand-capturing-lifts [38] 100.0% [42] 636(8.8%) 320(4.4%) lambda-clause-expander (unknown source) finish-bodys [46] 49.7% --------------------------------------------------------------------- ival-add [36] 26.2% ival-div [37] 35.6% bf* [40] 38.2% [43] 2626(36.4%) 700(9.7%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfadd [47] 73.3% --------------------------------------------------------------------- classify-ival [41] 100.0% [44] 698(9.7%) 698(9.7%) force ...collects/racket/private/promise.rkt:109:0 --------------------------------------------------------------------- ...higher-order.rkt:361:33 [39]100.0% [45] 348(4.8%) 348(4.8%) ??? ...cts/racket/contract/private/list.rkt:209:10 --------------------------------------------------------------------- lambda-clause-expander [42] 16.7% for-loop [48] 83.3% [46] 316(4.4%) 0(0.0%) finish-bodys (unknown source) for-loop [48] 100.0% --------------------------------------------------------------------- ??? [43] 100.0% [47] 1926(26.7%) 0(0.0%) bfadd ...-lib/math/private/bigfloat/mpfr.rkt:821:4 ??? [49] 85.0% new-mpfr [51] 15.0% --------------------------------------------------------------------- for-loop [48] 14.3% finish-bodys [46] 85.7% [48] 316(4.4%) 0(0.0%) for-loop (unknown source) finish-bodys [46] 71.4% for-loop [48] 14.3% expand-implicit [50] 14.3% --------------------------------------------------------------------- bfadd [47] 100.0% [49] 1638(22.7%) 1638(22.7%) ??? /opt/racket-7.0/collects/ffi/unsafe.rkt:898:4 --------------------------------------------------------------------- for-loop [48] 100.0% [50] 316(4.4%) 316(4.4%) expand-implicit (unknown source) --------------------------------------------------------------------- bfadd [47] 100.0% [51] 288(4.0%) 288(4.0%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ---------------------------------------------------------------------