Profiling results ----------------- Total cpu time observed: 8400ms (out of 8572ms) Number of samples taken: 28 (once every 300ms) ==================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ==================================================================== ??? [6] 100.0% [1] 8400(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [4] 100.0% -------------------------------------------------------------------- ??? [6] 100.0% [2] 664(7.9%) 0(0.0%) errors ...s/herbie/no-costs-2/src/points.rkt:223:0 for-loop [5] 100.0% -------------------------------------------------------------------- loop [59] 100.0% [3] 330(3.9%) 0(0.0%) ...higher-order.rkt:361:33 (unknown source) ??? [60] 100.0% -------------------------------------------------------------------- profile-thunk16 [1] 100.0% [4] 8400(100.0%) 0(0.0%) run ...et-7.0/share/pkgs/profile-lib/main.rkt:39:2 ??? [6] 100.0% -------------------------------------------------------------------- errors [2] 100.0% [5] 664(7.9%) 0(0.0%) for-loop ...herbie/no-costs-2/src/points.rkt:225:2 ??? [6] 100.0% -------------------------------------------------------------------- for-loop [5] 2.6% run [4] 48.7% [6] 8400(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 48.7% prepare-points [17] 22.5% run-improve43 [7] 9.1% oracle-error [8] 5.3% eval-errors [22] 3.6% errors [2] 2.6% ??? [52] 1.9% errors-score [9] 1.8% baseline-error [10] 1.8% ordinary-value? [35] 1.3% ulp-difference [68] 1.3% -------------------------------------------------------------------- ??? [6] 100.0% [7] 1524(18.1%) 0(0.0%) run-improve43 ...no-costs-2/src/mainloop.rkt:338:0 setup-prog!34 [12] 46.7% simplify! [13] 31.6% for-loop [14] 21.7% -------------------------------------------------------------------- ??? [6] 100.0% [8] 898(10.7%) 0(0.0%) oracle-error ...ie/no-costs-2/src/points.rkt:207:0 for-loop [11] 100.0% -------------------------------------------------------------------- ??? [6] 100.0% [9] 308(3.7%) 0(0.0%) errors-score ...ie/no-costs-2/src/points.rkt:215:0 loop [15] 100.0% -------------------------------------------------------------------- ??? [6] 100.0% [10] 298(3.5%) 0(0.0%) baseline-error .../no-costs-2/src/points.rkt:211:0 argmin [16] 100.0% -------------------------------------------------------------------- oracle-error [8] 100.0% [11] 898(10.7%) 0(0.0%) for-loop ...herbie/no-costs-2/src/points.rkt:208:2 map [51] 66.6% loop [59] 33.4% -------------------------------------------------------------------- run-improve43 [7] 100.0% [12] 712(8.5%) 0(0.0%) setup-prog!34 .../no-costs-2/src/mainloop.rkt:67:0 prepare-points [17] 100.0% -------------------------------------------------------------------- run-improve43 [7] 100.0% [13] 482(5.7%) 0(0.0%) simplify! ...bie/no-costs-2/src/mainloop.rkt:216:0 ??? [18] 100.0% -------------------------------------------------------------------- run-improve43 [7] 100.0% [14] 330(3.9%) 0(0.0%) for-loop ...rbie/no-costs-2/src/mainloop.rkt:353:4 run-iter! [19] 100.0% -------------------------------------------------------------------- errors-score [9] 100.0% [15] 308(3.7%) 0(0.0%) loop .../racket-7.0/collects/racket/list.rkt:559:2 ordinary-value? [35] 100.0% -------------------------------------------------------------------- baseline-error [10] 100.0% [16] 298(3.5%) 0(0.0%) argmin ...acket-7.0/collects/racket/list.rkt:765:0 ??? [20] 100.0% -------------------------------------------------------------------- setup-prog!34 [12] 15.8% ??? [6] 84.2% [17] 4498(53.5%) 0(0.0%) prepare-points .../no-costs-2/src/points.rkt:166:0 loop [21] 87.8% eval-prog [23] 12.2% -------------------------------------------------------------------- simplify! [13] 100.0% [18] 482(5.7%) 0(0.0%) ??? ...erbie/no-costs-2/src/core/simplify.rkt:26:0 for-loop [25] 55.6% unpack376 [26] 44.4% -------------------------------------------------------------------- for-loop [14] 100.0% [19] 330(3.9%) 0(0.0%) run-iter! ...bie/no-costs-2/src/mainloop.rkt:319:0 localize! [24] 100.0% -------------------------------------------------------------------- argmin [16] 100.0% [20] 298(3.5%) 0(0.0%) ??? ...ies/herbie/no-costs-2/src/points.rkt:212:27 eval-errors [22] 100.0% -------------------------------------------------------------------- prepare-points [17] 100.0% [21] 3950(47.0%) 298(3.5%) loop ...ies/herbie/no-costs-2/src/points.rkt:143:4 loop [27] 60.5% ??? [29] 15.8% ordinary-value? [35] 8.8% andmap [32] 7.3% -------------------------------------------------------------------- ??? [20] 33.1% ??? [6] 66.9% [22] 900(10.7%) 0(0.0%) eval-errors ...bie/no-costs-2/src/points.rkt:198:0 for-loop [28] 100.0% -------------------------------------------------------------------- prepare-points [17] 100.0% [23] 548(6.5%) 0(0.0%) eval-prog ...rbie/no-costs-2/src/programs.rkt:83:0 loop [31] 59.1% hash-ref! [36] 40.9% -------------------------------------------------------------------- run-iter! [19] 100.0% [24] 330(3.9%) 0(0.0%) localize! ...bie/no-costs-2/src/mainloop.rkt:119:0 localize-error [30] 100.0% -------------------------------------------------------------------- ??? [18] 100.0% [25] 268(3.2%) 0(0.0%) for-loop .../no-costs-2/src/core/simplify.rkt:33:2 one-iter [33] 100.0% -------------------------------------------------------------------- ??? [18] 100.0% [26] 214(2.5%) 214(2.5%) unpack376 ...7.0/collects/racket/format.rkt:147:16 -------------------------------------------------------------------- loop [21] 100.0% [27] 2390(28.5%) 0(0.0%) loop ...ies/herbie/no-costs-2/src/points.rkt:102:2 ??? [34] 100.0% -------------------------------------------------------------------- eval-errors [22] 100.0% [28] 900(10.7%) 0(0.0%) for-loop ...herbie/no-costs-2/src/points.rkt:200:2 ??? [34] 100.0% -------------------------------------------------------------------- loop [21] 100.0% [29] 626(7.5%) 0(0.0%) ??? ...lies/herbie/no-costs-2/src/points.rkt:127:2 ??? [49] 52.7% compose [39] 47.3% -------------------------------------------------------------------- localize! [24] 100.0% [30] 330(3.9%) 0(0.0%) localize-error ...sts-2/src/core/localize.rkt:57:0 hash-ref! [36] 100.0% -------------------------------------------------------------------- loop [31] 33.3% expand-capturing-lifts [37] 33.3% eval-prog [23] 33.3% [31] 324(3.9%) 0(0.0%) loop (unknown source) expand-capturing-lifts [37] 33.3% loop [31] 33.3% for-loop [38] 33.3% -------------------------------------------------------------------- loop [21] 100.0% [32] 290(3.5%) 0(0.0%) andmap ...0/collects/racket/private/map.rkt:102:13 ordinary-value? [35] 100.0% -------------------------------------------------------------------- for-loop [25] 100.0% [33] 268(3.2%) 0(0.0%) one-iter .../no-costs-2/src/core/simplify.rkt:65:0 for-loop [40] 100.0% -------------------------------------------------------------------- ??? [62] 7.7% loop [59] 7.7% for-loop [28] 23.1% loop [27] 61.4% [34] 3890(46.3%) 300(3.6%) ??? ...ies/herbie/no-costs-2/src/programs.rkt:99:2 ??? [49] 47.9% ??? [41] 44.4% -------------------------------------------------------------------- andmap [32] 22.7% loop [15] 24.1% ??? [6] 26.1% loop [21] 27.1% [35] 1278(15.2%) 0(0.0%) ordinary-value? ...e/no-costs-2/src/float.rkt:80:0 special-value? [42] 100.0% -------------------------------------------------------------------- loop [59] 20.2% eval-prog [23] 20.2% localize-error [30] 59.6% [36] 554(6.6%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 parse-loop30 [43] 59.6% ??? [48] 20.2% bigfloat-hash [47] 20.2% -------------------------------------------------------------------- loop [31] 100.0% [37] 324(3.9%) 0(0.0%) expand-capturing-lifts (unknown source) loop [31] 100.0% -------------------------------------------------------------------- run-module-instance!125 [45] 50.0% loop [31] 50.0% [38] 324(3.9%) 0(0.0%) for-loop (unknown source) run-module-instance!125 [45] 50.0% [running body] [44] 50.0% -------------------------------------------------------------------- ??? [29] 100.0% [39] 296(3.5%) 296(3.5%) compose ...collects/racket/private/list.rkt:385:12 -------------------------------------------------------------------- one-iter [33] 100.0% [40] 268(3.2%) 0(0.0%) for-loop .../no-costs-2/src/core/simplify.rkt:67:2 for-loop [46] 100.0% -------------------------------------------------------------------- ??? [34] 100.0% [41] 1726(20.5%) 0(0.0%) ??? ...t-7.0/collects/racket/private/kw.rkt:592:14 ??? [49] 63.8% ??? [52] 18.7% ??? [54] 17.5% -------------------------------------------------------------------- ordinary-value? [35] 100.0% [42] 1278(15.2%) 0(0.0%) special-value? ...ie/no-costs-2/src/float.rkt:76:0 for-loop [50] 100.0% -------------------------------------------------------------------- hash-ref! [36] 100.0% [43] 330(3.9%) 0(0.0%) parse-loop30 ...s/racket/match/compiler.rkt:418:15 map [51] 100.0% -------------------------------------------------------------------- for-loop [38] 100.0% [44] 324(3.9%) 0(0.0%) [running body] .../collects/syntax/id-set.rkt":##f do-partial-app [53] 100.0% -------------------------------------------------------------------- for-loop [38] 100.0% [45] 324(3.9%) 0(0.0%) run-module-instance!125 (unknown source) for-loop [38] 100.0% -------------------------------------------------------------------- for-loop [40] 100.0% [46] 268(3.2%) 0(0.0%) for-loop .../no-costs-2/src/core/simplify.rkt:70:4 mk-enode! [55] 100.0% -------------------------------------------------------------------- hash-ref! [36] 100.0% [47] 224(2.7%) 0(0.0%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:167:0 bfcanonicalize [56] 100.0% -------------------------------------------------------------------- hash-ref! [36] 100.0% [48] 224(2.7%) 0(0.0%) ??? ...es/herbie/no-costs-2/src/programs.rkt:142:5 loop [59] 100.0% -------------------------------------------------------------------- ??? [29] 10.0% ??? [41] 33.4% ??? [34] 56.6% [49] 3296(39.2%) 300(3.6%) ??? ...tract/private/arrow-higher-order.rkt:357:33 ival-mult [57] 33.4% mk-ival [58] 29.3% ->flonum [61] 18.1% sample-multi-bounded [63] 10.0% -------------------------------------------------------------------- special-value? [42] 100.0% [50] 1278(15.2%) 598(7.1%) for-loop ...s/herbie/no-costs-2/src/float.rkt:17:3 ??? [77] 53.2% -------------------------------------------------------------------- parse-loop30 [43] 35.6% for-loop [11] 64.4% [51] 928(11.0%) 0(0.0%) map ...t-7.0/collects/racket/private/map.rkt:35:13 ??? [62] 64.4% loop [59] 35.6% -------------------------------------------------------------------- ??? [6] 49.8% ??? [41] 50.2% [52] 642(7.6%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:361:33 ival-sub [65] 50.2% ??? [60] 49.8% -------------------------------------------------------------------- [running body] [44] 100.0% [53] 324(3.9%) 0(0.0%) do-partial-app ...ntract/private/provide.rkt:431:0 ??? [64] 100.0% -------------------------------------------------------------------- ??? [41] 100.0% [54] 302(3.6%) 302(3.6%) ??? ...bie/no-costs-2/src/syntax/syntax.rkt:207:17 -------------------------------------------------------------------- for-loop [46] 100.0% [55] 268(3.2%) 268(3.2%) mk-enode! ...e/no-costs-2/src/core/egraph.rkt:97:0 -------------------------------------------------------------------- bigfloat-hash [47] 100.0% [56] 224(2.7%) 224(2.7%) bfcanonicalize .../private/bigfloat/mpfr.rkt:157:0 -------------------------------------------------------------------- ??? [49] 100.0% [57] 1102(13.1%) 0(0.0%) ival-mult .../no-costs-2/src/biginterval.rkt:112:0 bfmul [66] 100.0% -------------------------------------------------------------------- ??? [49] 100.0% [58] 966(11.5%) 0(0.0%) mk-ival ...bie/no-costs-2/src/biginterval.rkt:57:0 bf [67] 100.0% -------------------------------------------------------------------- map [51] 1.4% ??? [48] 26.2% for-loop [11] 35.1% loop [59] 37.3% [59] 854(10.2%) 0(0.0%) loop ...-7.0/collects/racket/private/map.rkt:40:19 loop [59] 37.3% ??? [34] 35.1% hash-ref! [36] 26.2% ...higher-order.rkt:361:33 [3] 1.4% -------------------------------------------------------------------- for-loop [71] 24.6% ??? [52] 24.6% ...higher-order.rkt:361:33 [3] 50.8% [60] 650(7.7%) 650(7.7%) ??? ...cts/racket/contract/private/list.rkt:209:10 for-loop [71] 24.6% -------------------------------------------------------------------- ??? [49] 100.0% [61] 598(7.1%) 0(0.0%) ->flonum .../herbie/no-costs-2/src/float.rkt:149:0 ??? [69] 100.0% -------------------------------------------------------------------- map [51] 100.0% [62] 598(7.1%) 0(0.0%) ??? ...ies/herbie/no-costs-2/src/points.rkt:209:26 ??? [34] 50.2% ulp-difference [68] 49.8% -------------------------------------------------------------------- ??? [49] 100.0% [63] 330(3.9%) 0(0.0%) sample-multi-bounded ...osts-2/src/points.rkt:15:0 loop [70] 100.0% -------------------------------------------------------------------- do-partial-app [53] 100.0% [64] 324(3.9%) 324(3.9%) ??? ...ntract/private/arrow-higher-order.rkt:554:2 -------------------------------------------------------------------- ??? [52] 100.0% [65] 322(3.8%) 0(0.0%) ival-sub ...e/no-costs-2/src/biginterval.rkt:100:0 bfmul [66] 100.0% -------------------------------------------------------------------- ival-sub [65] 22.6% ival-mult [57] 77.4% [66] 1424(17.0%) 0(0.0%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:821:4 ??? [72] 66.6% new-mpfr [73] 33.4% -------------------------------------------------------------------- mk-ival [58] 100.0% [67] 966(11.5%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 ??? [72] 64.4% new-mpfr [73] 35.6% -------------------------------------------------------------------- ??? [62] 47.5% ??? [6] 52.5% [68] 628(7.5%) 0(0.0%) ulp-difference ...ie/no-costs-2/src/float.rkt:46:0 infer-representation [74] 100.0% -------------------------------------------------------------------- ->flonum [61] 100.0% [69] 598(7.1%) 0(0.0%) ??? ...htlies/herbie/no-costs-2/src/float.rkt:23:2 for-loop [75] 100.0% -------------------------------------------------------------------- sample-multi-bounded [63] 100.0% [70] 330(3.9%) 0(0.0%) loop ...lies/herbie/no-costs-2/src/points.rkt:42:6 random-exp [76] 100.0% -------------------------------------------------------------------- ??? [60] 100.0% [71] 320(3.8%) 0(0.0%) for-loop ...acket/contract/private/list.rkt:212:15 ??? [60] 100.0% -------------------------------------------------------------------- bf [67] 39.6% bfmul [66] 60.4% [72] 1570(18.7%) 1570(18.7%) ??? /opt/racket-7.0/collects/ffi/unsafe.rkt:898:4 -------------------------------------------------------------------- bf [67] 42.0% bfmul [66] 58.0% [73] 820(9.8%) 820(9.8%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 -------------------------------------------------------------------- ulp-difference [68] 100.0% [74] 628(7.5%) 298(3.5%) infer-representation ...costs-2/src/float.rkt:15:0 ??? [77] 52.5% -------------------------------------------------------------------- ??? [69] 100.0% [75] 598(7.1%) 598(7.1%) for-loop ...s/herbie/no-costs-2/src/float.rkt:24:5 -------------------------------------------------------------------- loop [70] 100.0% [76] 330(3.9%) 330(3.9%) random-exp ...rbie/no-costs-2/src/common.rkt:172:0 -------------------------------------------------------------------- infer-representation [74] 32.7% for-loop [50] 67.3% [77] 1010(12.0%) 1010(12.0%) ??? ...s/racket/contract/private/arr-i.rkt:1252:29 --------------------------------------------------------------------