Profiling results ----------------- Total cpu time observed: 8356ms (out of 8368ms) Number of samples taken: 54 (once every 155ms) ====================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ====================================================================== ??? [6] 100.0% [1] 8356(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [4] 100.0% ---------------------------------------------------------------------- make-alt-table [32] 4.3% ??? [6] 95.7% [2] 1290(15.4%) 0(0.0%) errors ...lies/herbie/develop/src/points.rkt:223:0 for-loop [5] 100.0% ---------------------------------------------------------------------- hash-ref! [50] 100.0% [3] 42(0.5%) 0(0.0%) ??? ...tlies/herbie/develop/src/programs.rkt:142:5 loop [22] 100.0% ---------------------------------------------------------------------- profile-thunk16 [1] 100.0% [4] 8356(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] 1290(15.4%) 200(2.4%) for-loop ...es/herbie/develop/src/points.rkt:225:2 ??? [6] 84.5% ---------------------------------------------------------------------- for-loop [5] 4.3% run [4] 47.8% [6] 8356(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 47.8% prepare-points [19] 21.2% run-improve43 [7] 8.0% eval-errors [8] 5.4% errors [2] 5.3% oracle-error [9] 3.8% ulp-difference [20] 2.8% get-final-combination [10] 2.1% errors-score [11] 2.0% ??? [46] 1.6% ---------------------------------------------------------------------- ??? [6] 100.0% [7] 1352(16.2%) 0(0.0%) run-improve43 ...ie/develop/src/mainloop.rkt:339:0 setup-prog!34 [13] 56.2% for-loop [15] 29.6% simplify! [17] 10.8% finalize-iter! [18] 3.4% ---------------------------------------------------------------------- ??? [6] 100.0% [8] 904(10.8%) 0(0.0%) eval-errors ...herbie/develop/src/points.rkt:198:0 for-loop [12] 100.0% ---------------------------------------------------------------------- ??? [6] 100.0% [9] 630(7.5%) 0(0.0%) oracle-error ...erbie/develop/src/points.rkt:207:0 for-loop [14] 100.0% ---------------------------------------------------------------------- ??? [6] 100.0% [10] 358(4.3%) 358(4.3%) get-final-combination ...op/src/mainloop.rkt:360:0 ---------------------------------------------------------------------- ??? [6] 100.0% [11] 336(4.0%) 0(0.0%) errors-score ...erbie/develop/src/points.rkt:215:0 loop [16] 100.0% ---------------------------------------------------------------------- eval-errors [8] 100.0% [12] 904(10.8%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:200:2 ??? [46] 35.0% ??? [63] 34.3% ulp-difference [20] 30.8% ---------------------------------------------------------------------- run-improve43 [7] 100.0% [13] 760(9.1%) 0(0.0%) setup-prog!34 ...bie/develop/src/mainloop.rkt:67:0 prepare-points [19] 80.3% ??? [24] 14.5% reset! [26] 5.3% ---------------------------------------------------------------------- oracle-error [9] 100.0% [14] 630(7.5%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:208:2 map [23] 50.2% loop [22] 49.8% ---------------------------------------------------------------------- run-improve43 [7] 100.0% [15] 400(4.8%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:354:4 run-iter! [21] 100.0% ---------------------------------------------------------------------- errors-score [11] 100.0% [16] 336(4.0%) 336(4.0%) loop .../racket-7.2/collects/racket/list.rkt:559:2 ---------------------------------------------------------------------- run-improve43 [7] 100.0% [17] 146(1.7%) 0(0.0%) simplify! ...herbie/develop/src/mainloop.rkt:217:0 ??? [25] 100.0% ---------------------------------------------------------------------- run-improve43 [7] 100.0% [18] 46(0.6%) 0(0.0%) finalize-iter! ...e/develop/src/mainloop.rkt:271:0 ??? [24] 100.0% ---------------------------------------------------------------------- setup-prog!34 [13] 14.7% ??? [6] 85.3% [19] 4152(49.7%) 0(0.0%) prepare-points ...bie/develop/src/points.rkt:166:0 loop [27] 88.7% eval-prog [29] 11.3% ---------------------------------------------------------------------- for-loop [12] 28.7% ??? [6] 71.3% [20] 970(11.6%) 0(0.0%) ulp-difference ...erbie/develop/src/float.rkt:46:0 infer-representation [28] 100.0% ---------------------------------------------------------------------- for-loop [15] 100.0% [21] 400(4.8%) 0(0.0%) run-iter! ...herbie/develop/src/mainloop.rkt:320:0 gen-series! [30] 84.5% localize! [34] 15.5% ---------------------------------------------------------------------- ??? [3] 11.8% for-loop [14] 88.2% [22] 356(4.3%) 0(0.0%) loop ...-7.2/collects/racket/private/map.rkt:40:19 ??? [46] 88.2% hash-ref! [50] 11.8% ---------------------------------------------------------------------- for-loop [14] 100.0% [23] 316(3.8%) 0(0.0%) map ...t-7.2/collects/racket/private/map.rkt:35:13 ??? [31] 100.0% ---------------------------------------------------------------------- finalize-iter! [18] 29.5% setup-prog!34 [13] 70.5% [24] 156(1.9%) 46(0.6%) ??? ...contract/private/arrow-val-first.rkt:390:18 make-alt-table [32] 70.5% ---------------------------------------------------------------------- simplify! [17] 100.0% [25] 146(1.7%) 0(0.0%) ??? ...s/herbie/develop/src/core/simplify.rkt:26:0 one-iter [33] 58.9% unpack376 [35] 41.1% ---------------------------------------------------------------------- setup-prog!34 [13] 100.0% [26] 40(0.5%) 0(0.0%) reset! ...tlies/herbie/develop/src/config.rkt:98:0 for-loop [36] 100.0% ---------------------------------------------------------------------- prepare-points [19] 100.0% [27] 3684(44.1%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:143:4 loop [37] 67.9% ??? [38] 24.0% ordinary-value? [40] 8.1% ---------------------------------------------------------------------- ulp-difference [20] 100.0% [28] 970(11.6%) 692(8.3%) infer-representation ...develop/src/float.rkt:15:0 return/no-unsupplied [88] 28.7% ---------------------------------------------------------------------- prepare-points [19] 100.0% [29] 468(5.6%) 0(0.0%) eval-prog .../herbie/develop/src/programs.rkt:83:0 loop [39] 80.8% eval [44] 10.3% hash-ref! [50] 9.0% ---------------------------------------------------------------------- run-iter! [21] 100.0% [30] 338(4.0%) 338(4.0%) gen-series! ...rbie/develop/src/mainloop.rkt:153:0 ---------------------------------------------------------------------- map [23] 100.0% [31] 316(3.8%) 0(0.0%) ??? ...htlies/herbie/develop/src/points.rkt:209:26 ??? [63] 100.0% ---------------------------------------------------------------------- ??? [24] 100.0% [32] 110(1.3%) 0(0.0%) make-alt-table ...elop/src/core/alt-table.rkt:33:0 errors [2] 50.9% in-pcontext [42] 49.1% ---------------------------------------------------------------------- ??? [25] 100.0% [33] 86(1.0%) 0(0.0%) one-iter ...bie/develop/src/core/simplify.rkt:85:0 for-loop [43] 58.1% find-matches [45] 41.9% ---------------------------------------------------------------------- run-iter! [21] 100.0% [34] 62(0.7%) 0(0.0%) localize! ...herbie/develop/src/mainloop.rkt:120:0 localize-error [41] 100.0% ---------------------------------------------------------------------- ??? [25] 100.0% [35] 60(0.7%) 60(0.7%) unpack376 ...7.2/collects/racket/format.rkt:147:16 ---------------------------------------------------------------------- reset! [26] 100.0% [36] 40(0.5%) 40(0.5%) for-loop ...ies/herbie/develop/src/config.rkt:99:2 ---------------------------------------------------------------------- loop [27] 100.0% [37] 2500(29.9%) 104(1.2%) loop ...htlies/herbie/develop/src/points.rkt:102:2 ??? [46] 95.8% ---------------------------------------------------------------------- loop [27] 100.0% [38] 886(10.6%) 80(1.0%) ??? ...ghtlies/herbie/develop/src/points.rkt:127:2 ??? [63] 84.4% do-curry [78] 6.5% ---------------------------------------------------------------------- loop [39] 33.3% expand-capturing-lifts [47] 33.3% eval-prog [29] 33.3% [39] 378(4.5%) 0(0.0%) loop (unknown source) expand-capturing-lifts [47] 33.3% loop [39] 33.3% for-loop [48] 33.3% ---------------------------------------------------------------------- loop [27] 100.0% [40] 298(3.6%) 0(0.0%) ordinary-value? ...rbie/develop/src/float.rkt:80:0 special-value? [49] 100.0% ---------------------------------------------------------------------- localize! [34] 100.0% [41] 62(0.7%) 0(0.0%) localize-error ...velop/src/core/localize.rkt:57:0 hash-ref! [50] 100.0% ---------------------------------------------------------------------- make-alt-table [32] 100.0% [42] 54(0.6%) 54(0.6%) in-pcontext .../herbie/develop/src/points.rkt:60:0 ---------------------------------------------------------------------- one-iter [33] 100.0% [43] 50(0.6%) 0(0.0%) for-loop ...bie/develop/src/core/simplify.rkt:94:2 loop [51] 100.0% ---------------------------------------------------------------------- eval-prog [29] 100.0% [44] 48(0.6%) 0(0.0%) eval (unknown source) namespace-syntax-introduce3 [52]100.0% ---------------------------------------------------------------------- one-iter [33] 100.0% [45] 36(0.4%) 0(0.0%) find-matches ...develop/src/core/simplify.rkt:55:0 for-loop [53] 100.0% ---------------------------------------------------------------------- loop [22] 9.2% for-loop [12] 9.2% ??? [6] 11.6% loop [37] 70.0% [46] 3424(41.0%) 136(1.6%) ??? ...htlies/herbie/develop/src/programs.rkt:99:2 ??? [63] 58.4% ??? [54] 37.6% ---------------------------------------------------------------------- loop [39] 100.0% [47] 378(4.5%) 0(0.0%) expand-capturing-lifts (unknown source) loop [39] 100.0% ---------------------------------------------------------------------- temp37_0 [56] 3.7% for-loop [48] 8.4% loop [39] 39.8% run-module-instance!125 [55] 48.1% [48] 378(4.5%) 110(1.3%) for-loop (unknown source) run-module-instance!125 [55] 48.1% temp37_0 [56] 39.2% for-loop [48] 8.4% ---------------------------------------------------------------------- ordinary-value? [40] 100.0% [49] 298(3.6%) 0(0.0%) special-value? ...erbie/develop/src/float.rkt:76:0 for-loop [57] 100.0% ---------------------------------------------------------------------- eval-prog [29] 13.5% loop [22] 26.9% localize-error [41] 59.6% [50] 104(1.2%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 parse-loop30 [58] 59.6% ??? [3] 26.9% bigfloat-hash [61] 13.5% ---------------------------------------------------------------------- for-loop [43] 100.0% [51] 50(0.6%) 0(0.0%) loop .../herbie/develop/src/core/simplify.rkt:99:4 apply-match [59] 100.0% ---------------------------------------------------------------------- eval [44] 100.0% [52] 48(0.6%) 0(0.0%) namespace-syntax-introduce3 (unknown source) free-identifier=? [60] 100.0% ---------------------------------------------------------------------- for-loop [53] 50.0% find-matches [45] 50.0% [53] 36(0.4%) 0(0.0%) for-loop ...bie/develop/src/core/simplify.rkt:57:8 for-loop [53] 50.0% match-e [62] 50.0% ---------------------------------------------------------------------- ??? [46] 100.0% [54] 1288(15.4%) 0(0.0%) ??? ...t-7.2/collects/racket/private/kw.rkt:594:14 ??? [63] 100.0% ---------------------------------------------------------------------- for-loop [48] 100.0% [55] 378(4.5%) 0(0.0%) run-module-instance!125 (unknown source) for-loop [48] 100.0% ---------------------------------------------------------------------- for-loop [48] 100.0% [56] 310(3.7%) 58(0.7%) temp37_0 (unknown source) [running body] [65] 16.1% [running body] [68] 14.8% for-loop [48] 13.5% [running body] [70] 12.9% [running body] [71] 12.3% [running body] [72] 11.6% ---------------------------------------------------------------------- special-value? [49] 100.0% [57] 298(3.6%) 298(3.6%) for-loop ...lies/herbie/develop/src/float.rkt:17:3 ---------------------------------------------------------------------- hash-ref! [50] 100.0% [58] 62(0.7%) 0(0.0%) parse-loop30 ...s/racket/match/compiler.rkt:418:15 for-loop [64] 100.0% ---------------------------------------------------------------------- loop [51] 100.0% [59] 50(0.6%) 0(0.0%) apply-match .../develop/src/core/simplify.rkt:64:0 make-sequence [66] 100.0% ---------------------------------------------------------------------- namespace-syntax-introduce3 [52]100.0% [60] 48(0.6%) 0(0.0%) free-identifier=? (unknown source) resolve+shift28 [67] 100.0% ---------------------------------------------------------------------- hash-ref! [50] 100.0% [61] 42(0.5%) 0(0.0%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:167:0 bfcanonicalize [69] 100.0% ---------------------------------------------------------------------- for-loop [53] 100.0% [62] 36(0.4%) 0(0.0%) match-e ...herbie/develop/src/core/ematch.rkt:39:0 for-loop [73] 100.0% ---------------------------------------------------------------------- for-loop [12] 6.6% ??? [31] 6.8% ??? [38] 16.0% ??? [54] 27.6% ??? [46] 42.9% [63] 4662(55.8%) 470(5.6%) ??? ...tract/private/arrow-higher-order.rkt:360:33 ->flonum [74] 34.3% ival-mult [75] 27.6% mk-ival [76] 22.1% sample-multi-bounded [77] 6.0% ---------------------------------------------------------------------- parse-loop30 [58] 100.0% [64] 62(0.7%) 62(0.7%) for-loop ...ie/develop/src/core/localize.rkt:39:21 ---------------------------------------------------------------------- temp37_0 [56] 100.0% [65] 50(0.6%) 50(0.6%) [running body] ...t/private/unit-contract.rkt":##f ---------------------------------------------------------------------- apply-match [59] 100.0% [66] 50(0.6%) 0(0.0%) make-sequence ...ects/racket/private/for.rkt:530:2 ??? [79] 100.0% ---------------------------------------------------------------------- free-identifier=? [60] 100.0% [67] 48(0.6%) 48(0.6%) resolve+shift28 (unknown source) ---------------------------------------------------------------------- temp37_0 [56] 100.0% [68] 46(0.6%) 46(0.6%) [running body] ...number-theory/factorial.rkt":##f ---------------------------------------------------------------------- bigfloat-hash [61] 100.0% [69] 42(0.5%) 0(0.0%) bfcanonicalize .../private/bigfloat/mpfr.rkt:157:0 bigfloat->sig+exp [80] 100.0% ---------------------------------------------------------------------- temp37_0 [56] 100.0% [70] 40(0.5%) 40(0.5%) [running body] ..." #%contract-defs-reference):##f ---------------------------------------------------------------------- temp37_0 [56] 100.0% [71] 38(0.5%) 38(0.5%) [running body] ...ed-racket/rep/rep-utils.rkt":##f ---------------------------------------------------------------------- temp37_0 [56] 100.0% [72] 36(0.4%) 36(0.4%) [running body] ...se/experimental/provide.rkt":##f ---------------------------------------------------------------------- match-e [62] 100.0% [73] 36(0.4%) 0(0.0%) for-loop ...rbie/develop/src/core/ematch.rkt:52:11 do-curry [78] 100.0% ---------------------------------------------------------------------- ??? [63] 100.0% [74] 1598(19.1%) 0(0.0%) ->flonum ...ies/herbie/develop/src/float.rkt:149:0 ??? [81] 100.0% ---------------------------------------------------------------------- ??? [63] 100.0% [75] 1288(15.4%) 0(0.0%) ival-mult ...bie/develop/src/biginterval.rkt:112:0 bfmul [82] 100.0% ---------------------------------------------------------------------- ??? [63] 100.0% [76] 1028(12.3%) 0(0.0%) mk-ival ...herbie/develop/src/biginterval.rkt:57:0 bf [83] 100.0% ---------------------------------------------------------------------- ??? [63] 100.0% [77] 278(3.3%) 278(3.3%) sample-multi-bounded ...evelop/src/points.rkt:15:0 ---------------------------------------------------------------------- for-loop [73] 38.3% ??? [38] 61.7% [78] 94(1.1%) 94(1.1%) do-curry ...-7.2/collects/racket/function.rkt:71:2 ---------------------------------------------------------------------- make-sequence [66] 100.0% [79] 50(0.6%) 50(0.6%) ??? ...t-7.2/collects/racket/private/for.rkt:450:7 ---------------------------------------------------------------------- bfcanonicalize [69] 100.0% [80] 42(0.5%) 0(0.0%) bigfloat->sig+exp ...ivate/bigfloat/mpfr.rkt:372:0 new-mpz [84] 100.0% ---------------------------------------------------------------------- ->flonum [74] 100.0% [81] 1598(19.1%) 0(0.0%) ??? ...nightlies/herbie/develop/src/float.rkt:23:2 for-loop [86] 100.0% ---------------------------------------------------------------------- ival-mult [75] 100.0% [82] 1288(15.4%) 0(0.0%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [85] 100.0% ---------------------------------------------------------------------- mk-ival [76] 100.0% [83] 1028(12.3%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [85] 53.1% ??? [87] 46.9% ---------------------------------------------------------------------- bigfloat->sig+exp [80] 100.0% [84] 42(0.5%) 42(0.5%) new-mpz ...-lib/math/private/bigfloat/gmp.rkt:73:0 ---------------------------------------------------------------------- bf [83] 29.8% bfmul [82] 70.2% [85] 1834(21.9%) 1834(21.9%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ---------------------------------------------------------------------- ??? [81] 100.0% [86] 1598(19.1%) 940(11.2%) for-loop ...lies/herbie/develop/src/float.rkt:24:5 return/no-unsupplied [88] 41.2% ---------------------------------------------------------------------- bf [83] 100.0% [87] 482(5.8%) 482(5.8%) ??? /opt/racket-7.2/collects/ffi/unsafe.rkt:905:4 ---------------------------------------------------------------------- infer-representation [28] 29.7% for-loop [86] 70.3% [88] 936(11.2%) 620(7.4%) return/no-unsupplied ...ct/private/arr-i.rkt:542:0 filter [89] 33.8% ---------------------------------------------------------------------- return/no-unsupplied [88] 100.0% [89] 316(3.8%) 316(3.8%) filter ...2/collects/racket/private/list.rkt:256:2 ----------------------------------------------------------------------