Profiling results ----------------- Total cpu time observed: 25902ms (out of 26396ms) Number of samples taken: 58 (once every 447ms) =============================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee =============================================================================== ??? [6] 100.0% [1] 25902(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [4] 100.0% ------------------------------------------------------------------------------- ??? [6] 100.0% [2] 1632(6.3%) 0(0.0%) errors ...lies/herbie/develop/src/points.rkt:223:0 for-loop [5] 100.0% ------------------------------------------------------------------------------- hash-ref! [36] 100.0% [3] 360(1.4%) 0(0.0%) parse-loop30 ...s/racket/match/compiler.rkt:418:15 map [18] 100.0% ------------------------------------------------------------------------------- profile-thunk16 [1] 100.0% [4] 25902(100.0%) 0(0.0%) run ...et-7.2/share/pkgs/profile-lib/main.rkt:39:2 ??? [6] 100.0% ------------------------------------------------------------------------------- errors [2] 100.0% [5] 1632(6.3%) 268(1.0%) for-loop ...es/herbie/develop/src/points.rkt:225:2 ??? [6] 83.6% ------------------------------------------------------------------------------- for-loop [5] 1.8% run [4] 49.1% [6] 25902(100.0%) 742(2.9%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 49.1% prepare-points [17] 40.1% run-improve43 [7] 2.7% errors [2] 2.3% eval-errors [8] 1.8% oracle-error [9] 1.2% ??? [44] 1.2% errors-score [10] 0.6% ------------------------------------------------------------------------------- ??? [6] 100.0% [7] 1388(5.4%) 0(0.0%) run-improve43 ...ie/develop/src/mainloop.rkt:339:0 simplify! [12] 49.0% for-loop [14] 25.9% setup-prog!34 [15] 25.1% ------------------------------------------------------------------------------- ??? [6] 100.0% [8] 928(3.6%) 0(0.0%) eval-errors ...herbie/develop/src/points.rkt:198:0 for-loop [11] 100.0% ------------------------------------------------------------------------------- ??? [6] 100.0% [9] 614(2.4%) 0(0.0%) oracle-error ...erbie/develop/src/points.rkt:207:0 for-loop [13] 100.0% ------------------------------------------------------------------------------- ??? [6] 100.0% [10] 290(1.1%) 0(0.0%) errors-score ...erbie/develop/src/points.rkt:215:0 loop [16] 100.0% ------------------------------------------------------------------------------- eval-errors [8] 100.0% [11] 928(3.6%) 292(1.1%) for-loop ...es/herbie/develop/src/points.rkt:200:2 ulp-difference [21] 35.6% ??? [44] 33.0% ------------------------------------------------------------------------------- run-improve43 [7] 100.0% [12] 680(2.6%) 0(0.0%) simplify! ...herbie/develop/src/mainloop.rkt:217:0 ??? [19] 100.0% ------------------------------------------------------------------------------- oracle-error [9] 100.0% [13] 614(2.4%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:208:2 map [18] 100.0% ------------------------------------------------------------------------------- run-improve43 [7] 100.0% [14] 360(1.4%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:354:4 run-iter! [20] 100.0% ------------------------------------------------------------------------------- run-improve43 [7] 100.0% [15] 348(1.3%) 0(0.0%) setup-prog!34 ...bie/develop/src/mainloop.rkt:67:0 prepare-points [17] 100.0% ------------------------------------------------------------------------------- errors-score [10] 100.0% [16] 290(1.1%) 0(0.0%) loop .../racket-7.2/collects/racket/list.rkt:559:2 ordinary-value? [29] 100.0% ------------------------------------------------------------------------------- setup-prog!34 [15] 1.6% ??? [6] 98.4% [17] 21118(81.5%) 0(0.0%) prepare-points ...bie/develop/src/points.rkt:166:0 loop [22] 100.0% ------------------------------------------------------------------------------- parse-loop30 [3] 37.0% for-loop [13] 63.0% [18] 974(3.8%) 0(0.0%) map ...t-7.2/collects/racket/private/map.rkt:35:13 ??? [24] 63.0% hash-ref! [36] 37.0% ------------------------------------------------------------------------------- simplify! [12] 100.0% [19] 680(2.6%) 0(0.0%) ??? ...s/herbie/develop/src/core/simplify.rkt:26:0 for-loop [23] 100.0% ------------------------------------------------------------------------------- for-loop [14] 100.0% [20] 360(1.4%) 0(0.0%) run-iter! ...herbie/develop/src/mainloop.rkt:320:0 localize! [25] 100.0% ------------------------------------------------------------------------------- for-loop [11] 100.0% [21] 330(1.3%) 0(0.0%) ulp-difference ...erbie/develop/src/float.rkt:46:0 infer-representation [26] 100.0% ------------------------------------------------------------------------------- prepare-points [17] 100.0% [22] 21118(81.5%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:143:4 loop [27] 88.0% ??? [28] 7.3% ordinary-value? [29] 4.7% ------------------------------------------------------------------------------- ??? [19] 100.0% [23] 680(2.6%) 0(0.0%) for-loop ...bie/develop/src/core/simplify.rkt:33:2 one-iter [30] 100.0% ------------------------------------------------------------------------------- map [18] 100.0% [24] 614(2.4%) 0(0.0%) ??? ...htlies/herbie/develop/src/points.rkt:209:26 ??? [44] 51.5% ??? [32] 48.5% ------------------------------------------------------------------------------- run-iter! [20] 100.0% [25] 360(1.4%) 0(0.0%) localize! ...herbie/develop/src/mainloop.rkt:120:0 localize-error [31] 100.0% ------------------------------------------------------------------------------- ulp-difference [21] 100.0% [26] 330(1.3%) 330(1.3%) infer-representation ...develop/src/float.rkt:15:0 ------------------------------------------------------------------------------- loop [22] 100.0% [27] 18592(71.8%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:102:2 ??? [32] 100.0% ------------------------------------------------------------------------------- loop [22] 100.0% [28] 1540(5.9%) 344(1.3%) ??? ...ghtlies/herbie/develop/src/points.rkt:127:2 do-curry [35] 42.3% ??? [44] 35.3% ------------------------------------------------------------------------------- loop [16] 22.7% loop [22] 77.3% [29] 1276(4.9%) 0(0.0%) ordinary-value? ...rbie/develop/src/float.rkt:80:0 special-value? [33] 100.0% ------------------------------------------------------------------------------- for-loop [23] 100.0% [30] 680(2.6%) 0(0.0%) one-iter ...bie/develop/src/core/simplify.rkt:85:0 find-matches [34] 100.0% ------------------------------------------------------------------------------- localize! [25] 100.0% [31] 360(1.4%) 0(0.0%) localize-error ...velop/src/core/localize.rkt:57:0 hash-ref! [36] 100.0% ------------------------------------------------------------------------------- ??? [24] 1.6% loop [27] 98.4% [32] 18890(72.9%) 298(1.2%) ??? ...htlies/herbie/develop/src/programs.rkt:99:2 ??? [37] 63.8% ??? [44] 34.6% ------------------------------------------------------------------------------- ordinary-value? [29] 100.0% [33] 1276(4.9%) 354(1.4%) special-value? ...erbie/develop/src/float.rkt:76:0 for-loop [38] 72.3% ------------------------------------------------------------------------------- one-iter [30] 100.0% [34] 680(2.6%) 0(0.0%) find-matches ...develop/src/core/simplify.rkt:55:0 for-loop [39] 100.0% ------------------------------------------------------------------------------- ??? [28] 100.0% [35] 652(2.5%) 652(2.5%) do-curry ...-7.2/collects/racket/function.rkt:71:2 ------------------------------------------------------------------------------- localize-error [31] 25.0% map [18] 75.0% [36] 360(1.4%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 parse-loop30 [3] 75.0% ??? [40] 25.0% ------------------------------------------------------------------------------- ??? [32] 100.0% [37] 12060(46.6%) 0(0.0%) ??? ...t-7.2/collects/racket/private/kw.rkt:594:14 ??? [41] 81.2% ??? [44] 18.8% ------------------------------------------------------------------------------- special-value? [33] 100.0% [38] 922(3.6%) 922(3.6%) for-loop ...lies/herbie/develop/src/float.rkt:17:3 ------------------------------------------------------------------------------- for-loop [39] 50.0% find-matches [34] 50.0% [39] 680(2.6%) 0(0.0%) for-loop ...bie/develop/src/core/simplify.rkt:57:8 for-loop [39] 50.0% match-e [42] 50.0% ------------------------------------------------------------------------------- hash-ref! [36] 100.0% [40] 360(1.4%) 0(0.0%) ??? .../herbie/develop/src/core/localize.rkt:23:13 loop [43] 100.0% ------------------------------------------------------------------------------- ??? [37] 100.0% [41] 9792(37.8%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:364:33 ival-div [45] 49.6% ival-sqrt [46] 36.3% ival-sub [47] 14.2% ------------------------------------------------------------------------------- for-loop [39] 100.0% [42] 680(2.6%) 348(1.3%) match-e ...herbie/develop/src/core/ematch.rkt:39:0 for-loop [48] 48.8% ------------------------------------------------------------------------------- ??? [40] 10.0% loop [43] 90.0% [43] 360(1.4%) 0(0.0%) loop ...-7.2/collects/racket/private/map.rkt:40:19 loop [43] 90.0% ??? [44] 10.0% ------------------------------------------------------------------------------- for-loop [11] 2.7% ??? [24] 2.8% loop [43] 3.2% ??? [28] 4.8% ??? [6] 8.0% ??? [37] 20.2% ??? [32] 58.2% [44] 11228(43.3%) 306(1.2%) ??? ...tract/private/arrow-higher-order.rkt:360:33 mk-ival [49] 58.2% ival-mult [51] 20.2% ->flonum [52] 10.8% sample-multi-bounded [53] 4.8% bf [56] 3.2% ------------------------------------------------------------------------------- ??? [41] 100.0% [45] 4852(18.7%) 1492(5.8%) ival-div ...rbie/develop/src/biginterval.rkt:148:0 bfsub [55] 69.2% ------------------------------------------------------------------------------- ??? [41] 100.0% [46] 3550(13.7%) 300(1.2%) ival-sqrt ...bie/develop/src/biginterval.rkt:198:0 bfsqrt [50] 91.5% ------------------------------------------------------------------------------- ??? [41] 100.0% [47] 1390(5.4%) 0(0.0%) ival-sub ...rbie/develop/src/biginterval.rkt:100:0 bfsub [55] 100.0% ------------------------------------------------------------------------------- match-e [42] 100.0% [48] 332(1.3%) 0(0.0%) for-loop ...rbie/develop/src/core/ematch.rkt:52:11 procedure-reduce-keyword-arity-mask [54]100.0% ------------------------------------------------------------------------------- ??? [44] 100.0% [49] 6532(25.2%) 0(0.0%) mk-ival ...herbie/develop/src/biginterval.rkt:57:0 bf [56] 100.0% ------------------------------------------------------------------------------- ival-sqrt [46] 100.0% [50] 3250(12.5%) 542(2.1%) bfsqrt ...lib/math/private/bigfloat/mpfr.rkt:618:4 new-mpfr [59] 58.3% ??? [60] 25.0% ------------------------------------------------------------------------------- ??? [44] 100.0% [51] 2268(8.8%) 0(0.0%) ival-mult ...bie/develop/src/biginterval.rkt:112:0 bfsub [55] 100.0% ------------------------------------------------------------------------------- ??? [44] 100.0% [52] 1218(4.7%) 296(1.1%) ->flonum ...ies/herbie/develop/src/float.rkt:149:0 ??? [57] 75.7% ------------------------------------------------------------------------------- ??? [44] 100.0% [53] 544(2.1%) 0(0.0%) sample-multi-bounded ...evelop/src/points.rkt:15:0 loop [58] 100.0% ------------------------------------------------------------------------------- for-loop [48] 100.0% [54] 332(1.3%) 332(1.3%) procedure-reduce-keyword-arity-mask ....rkt:1562:4 ------------------------------------------------------------------------------- ival-sub [47] 19.8% ival-mult [51] 32.3% ival-div [45] 47.9% [55] 7018(27.1%) 304(1.2%) bfsub ...-lib/math/private/bigfloat/mpfr.rkt:821:4 ??? [60] 52.7% new-mpfr [59] 43.0% ------------------------------------------------------------------------------- ??? [44] 5.2% mk-ival [49] 94.8% [56] 6892(26.6%) 360(1.4%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [59] 49.0% ??? [60] 45.8% ------------------------------------------------------------------------------- ->flonum [52] 100.0% [57] 922(3.6%) 0(0.0%) ??? ...nightlies/herbie/develop/src/float.rkt:23:2 for-loop [61] 100.0% ------------------------------------------------------------------------------- sample-multi-bounded [53] 100.0% [58] 544(2.1%) 0(0.0%) loop ...ghtlies/herbie/develop/src/points.rkt:42:6 random-exp [62] 100.0% ------------------------------------------------------------------------------- bfsqrt [50] 22.9% bfsub [55] 36.4% bf [56] 40.7% [59] 8290(32.0%) 8290(32.0%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ------------------------------------------------------------------------------- bfsqrt [50] 10.6% bf [56] 41.2% bfsub [55] 48.3% [60] 7664(29.6%) 7664(29.6%) ??? /opt/racket-7.2/collects/ffi/unsafe.rkt:905:4 ------------------------------------------------------------------------------- ??? [57] 100.0% [61] 922(3.6%) 592(2.3%) for-loop ...lies/herbie/develop/src/float.rkt:24:5 return/no-unsupplied [63] 35.8% ------------------------------------------------------------------------------- loop [58] 100.0% [62] 544(2.1%) 544(2.1%) random-exp .../herbie/develop/src/common.rkt:172:0 ------------------------------------------------------------------------------- for-loop [61] 100.0% [63] 330(1.3%) 0(0.0%) return/no-unsupplied ...ct/private/arr-i.rkt:542:0 filter [64] 100.0% ------------------------------------------------------------------------------- return/no-unsupplied [63] 100.0% [64] 330(1.3%) 330(1.3%) filter ...2/collects/racket/private/list.rkt:256:2 -------------------------------------------------------------------------------