Profiling results ----------------- Total cpu time observed: 139692ms (out of 141900ms) Number of samples taken: 370 (once every 378ms) (Hiding functions with self<1.0% and local<2.0%: 2 of 68 hidden) ======================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ======================================================================== ??? [10] 100.0% [1] 139692(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [5] 100.0% ------------------------------------------------------------------------ loop [24] 0.2% loop [37] 99.8% [2] 131386(94.1%) 336(0.2%) ??? ...tlies/herbie/develop/src/programs.rkt:129:2 ??? [6] 92.9% bf [7] 6.6% ??? [42] 0.2% ------------------------------------------------------------------------ hash-ref! [30] 100.0% [3] 1372(1.0%) 0(0.0%) f8 ...et/collects/racket/match/compiler.rkt:507:40 map [8] 100.0% ------------------------------------------------------------------------ ??? [10] 100.0% [4] 612(0.4%) 0(0.0%) oracle-error ...erbie/develop/src/points.rkt:227:0 for-loop [9] 100.0% ------------------------------------------------------------------------ profile-thunk16 [1] 100.0% [5] 139692(100.0%) 0(0.0%) run ...racket/share/pkgs/profile-lib/main.rkt:39:2 ??? [10] 100.0% ------------------------------------------------------------------------ ??? [2] 100.0% [6] 122008(87.3%) 320(0.2%) ??? ...acket/collects/racket/private/kw.rkt:592:14 ??? [41] 99.5% ??? [42] 0.3% ------------------------------------------------------------------------ ??? [2] 100.0% [7] 8730(6.2%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [62] 88.5% ??? [63] 11.5% ------------------------------------------------------------------------ f8 [3] 100.0% [8] 1372(1.0%) 0(0.0%) map ...acket/collects/racket/private/map.rkt:35:13 hash-ref! [30] 62.6% loop [37] 37.4% ------------------------------------------------------------------------ oracle-error [4] 100.0% [9] 612(0.4%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:228:2 loop [37] 100.0% ------------------------------------------------------------------------ run [5] 50.0% [10] 139692(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 50.0% loop [17] 46.9% run-improve39 [11] 2.6% eval-errors [12] 0.2% oracle-error [4] 0.2% ------------------------------------------------------------------------ ??? [10] 100.0% [11] 7372(5.3%) 0(0.0%) run-improve39 ...ie/develop/src/mainloop.rkt:287:0 setup-prog!32 [13] 72.9% for-loop [14] 23.1% for-loop [16] 4.0% ------------------------------------------------------------------------ ??? [10] 100.0% [12] 654(0.5%) 0(0.0%) eval-errors ...herbie/develop/src/points.rkt:208:0 for-loop [15] 100.0% ------------------------------------------------------------------------ run-improve39 [11] 100.0% [13] 5374(3.8%) 0(0.0%) setup-prog!32 ...bie/develop/src/mainloop.rkt:63:0 loop [17] 100.0% ------------------------------------------------------------------------ run-improve39 [11] 100.0% [14] 1704(1.2%) 0(0.0%) for-loop ...herbie/develop/src/mainloop.rkt:304:10 run-iter! [18] 100.0% ------------------------------------------------------------------------ eval-errors [12] 100.0% [15] 654(0.5%) 654(0.5%) for-loop ...es/herbie/develop/src/points.rkt:210:2 ------------------------------------------------------------------------ run-improve39 [11] 100.0% [16] 294(0.2%) 0(0.0%) for-loop ...herbie/develop/src/mainloop.rkt:298:19 ??? [48] 100.0% ------------------------------------------------------------------------ setup-prog!32 [13] 3.9% ??? [10] 96.1% [17] 136428(97.7%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:167:2 loop [24] 51.6% loop [19] 33.1% make-exacts* [25] 12.4% make-exacts [20] 2.1% for-loop [22] 0.8% ------------------------------------------------------------------------ for-loop [14] 100.0% [18] 1704(1.2%) 0(0.0%) run-iter! ...herbie/develop/src/mainloop.rkt:268:0 localize-error [21] 80.5% simplify! [23] 19.5% ------------------------------------------------------------------------ loop [17] 100.0% [19] 45130(32.3%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:125:2 loop [24] 65.3% make-exacts* [25] 33.3% eval-prog [28] 1.4% ------------------------------------------------------------------------ loop [17] 100.0% [20] 2838(2.0%) 0(0.0%) make-exacts ...herbie/develop/src/points.rkt:123:0 make-exacts* [25] 56.9% loop [24] 43.1% ------------------------------------------------------------------------ run-iter! [18] 100.0% [21] 1372(1.0%) 0(0.0%) localize-error ...velop/src/core/localize.rkt:51:0 hash-ref! [30] 100.0% ------------------------------------------------------------------------ loop [17] 100.0% [22] 1044(0.7%) 0(0.0%) for-loop ...s/herbie/develop/src/points.rkt:182:19 for-loop [26] 100.0% ------------------------------------------------------------------------ run-iter! [18] 100.0% [23] 332(0.2%) 0(0.0%) simplify! ...herbie/develop/src/mainloop.rkt:182:0 for-loop [27] 100.0% ------------------------------------------------------------------------ make-exacts [20] 1.2% loop [19] 29.1% loop [17] 69.6% [24] 101140(72.4%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:108:4 loop [37] 99.7% ??? [2] 0.3% ------------------------------------------------------------------------ make-exacts [20] 4.8% loop [19] 44.7% loop [17] 50.5% [25] 33606(24.1%) 0(0.0%) make-exacts* ...erbie/develop/src/points.rkt:105:0 loop [37] 97.1% eval-prog [28] 2.9% ------------------------------------------------------------------------ for-loop [22] 100.0% [26] 1044(0.7%) 354(0.3%) for-loop ...es/herbie/develop/src/points.rkt:159:4 ??? [42] 66.1% ------------------------------------------------------------------------ simplify! [23] 100.0% [27] 332(0.2%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:186:6 for-loop [29] 100.0% ------------------------------------------------------------------------ loop [19] 39.6% make-exacts* [25] 60.4% [28] 1610(1.2%) 0(0.0%) eval-prog ...herbie/develop/src/programs.rkt:109:0 hash-ref! [30] 82.6% loop [31] 17.4% ------------------------------------------------------------------------ for-loop [27] 100.0% [29] 332(0.2%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:210:8 ??? [42] 100.0% ------------------------------------------------------------------------ map [8] 15.9% localize-error [21] 15.9% eval-prog [28] 27.1% loop [37] 41.1% [30] 2702(1.9%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 parse-loop14 [32] 25.4% f8 [3] 25.4% ??? [34] 24.6% bigfloat-hash [33] 24.6% ------------------------------------------------------------------------ loop [31] 33.3% expand-capturing-lifts [35] 33.3% eval-prog [28] 33.3% [31] 280(0.2%) 0(0.0%) loop (unknown source) expand-capturing-lifts [35] 33.3% loop [31] 33.3% for-loop [36] 33.3% ------------------------------------------------------------------------ hash-ref! [30] 100.0% [32] 1372(1.0%) 0(0.0%) parse-loop14 ...s/racket/match/compiler.rkt:418:15 loop [37] 74.8% ??? [41] 25.2% ------------------------------------------------------------------------ hash-ref! [30] 100.0% [33] 988(0.7%) 0(0.0%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:167:0 sig+exp->bigfloat [38] 100.0% ------------------------------------------------------------------------ hash-ref! [30] 100.0% [34] 988(0.7%) 0(0.0%) ??? ...tlies/herbie/develop/src/programs.rkt:153:5 loop [37] 100.0% ------------------------------------------------------------------------ loop [31] 100.0% [35] 280(0.2%) 0(0.0%) expand-capturing-lifts (unknown source) loop [31] 100.0% ------------------------------------------------------------------------ run-module-instance!125 [40] 50.0% loop [31] 50.0% [36] 280(0.2%) 0(0.0%) for-loop (unknown source) run-module-instance!125 [40] 50.0% temp37_0 [39] 50.0% ------------------------------------------------------------------------ map [8] 0.1% parse-loop14 [32] 0.1% for-loop [9] 0.4% ??? [34] 0.7% make-exacts* [25] 0.9% loop [24] 1.7% loop [37] 96.0% [37] 136088(97.4%) 660(0.5%) loop ...cket/collects/racket/private/map.rkt:40:19 loop [37] 96.0% ??? [2] 2.8% hash-ref! [30] 0.8% ??? [41] 0.1% ??? [42] 0.0% ------------------------------------------------------------------------ bigfloat-hash [33] 100.0% [38] 988(0.7%) 0(0.0%) sig+exp->bigfloat ...ivate/bigfloat/mpfr.rkt:387:0 new-mpfr [62] 100.0% ------------------------------------------------------------------------ for-loop [36] 100.0% [39] 280(0.2%) 280(0.2%) temp37_0 (unknown source) ------------------------------------------------------------------------ for-loop [36] 100.0% [40] 280(0.2%) 0(0.0%) run-module-instance!125 (unknown source) for-loop [36] 100.0% ------------------------------------------------------------------------ parse-loop14 [32] 0.3% loop [37] 0.8% ??? [6] 98.9% [41] 122752(87.9%) 368(0.3%) ??? ...tract/private/arrow-higher-order.rkt:361:33 bf-complex-exp [43] 75.9% bf-complex-div [44] 18.5% bf-complex-neg [45] 3.6% ------------------------------------------------------------------------ location-do [49] 4.1% for-loop [29] 4.1% ??? [6] 7.7% ??? [2] 7.8% for-loop [26] 17.1% loop [37] 59.2% [42] 4024(2.9%) 308(0.2%) ??? ...tract/private/arrow-higher-order.rkt:357:33 ->flonum [46] 66.9% sample-multi-bounded [47] 17.1% location-do [49] 4.1% ??? [48] 4.1% ------------------------------------------------------------------------ ??? [41] 100.0% [43] 93144(66.7%) 658(0.5%) bf-complex-exp .../develop/src/bigcomplex.rkt:41:0 ??? [54] 84.8% bf* [50] 13.5% ...higher-order.rkt:361:33 [51] 1.0% ------------------------------------------------------------------------ ??? [41] 100.0% [44] 22684(16.2%) 960(0.7%) bf-complex-div .../develop/src/bigcomplex.rkt:58:0 ??? [54] 61.1% bf* [50] 33.3% ...higher-order.rkt:361:33 [51] 1.4% ------------------------------------------------------------------------ ??? [41] 100.0% [45] 4438(3.2%) 368(0.3%) bf-complex-neg .../develop/src/bigcomplex.rkt:21:0 ??? [54] 62.1% ...higher-order.rkt:361:33 [51] 29.6% ------------------------------------------------------------------------ ??? [42] 100.0% [46] 2694(1.9%) 2084(1.5%) ->flonum ...s/herbie/develop/src/programs.rkt:36:0 return/no-unsupplied [53] 22.6% ------------------------------------------------------------------------ ??? [42] 100.0% [47] 690(0.5%) 690(0.5%) sample-multi-bounded ...evelop/src/points.rkt:44:0 ------------------------------------------------------------------------ for-loop [16] 47.0% ??? [42] 53.0% [48] 626(0.4%) 0(0.0%) ??? ...s/herbie/develop/src/core/simplify.rkt:31:0 iterate-egraph!7 [52] 100.0% ------------------------------------------------------------------------ ??? [42] 100.0% [49] 332(0.2%) 0(0.0%) location-do ...erbie/develop/src/programs.rkt:90:0 ??? [42] 100.0% ------------------------------------------------------------------------ bf-complex-div [44] 37.6% bf-complex-exp [43] 62.4% [50] 20088(14.4%) 320(0.2%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [54] 98.4% ------------------------------------------------------------------------ bf-complex-div [44] 12.4% bf-complex-exp [43] 36.9% bf-complex-neg [45] 50.7% [51] 2586(1.9%) 1312(0.9%) ...higher-order.rkt:361:33 (unknown source) ??? [55] 49.3% ------------------------------------------------------------------------ ??? [48] 100.0% [52] 626(0.4%) 0(0.0%) iterate-egraph!7 ...lop/src/core/simplify.rkt:55:0 one-iter [56] 100.0% ------------------------------------------------------------------------ ->flonum [46] 100.0% [53] 610(0.4%) 298(0.2%) return/no-unsupplied ...ct/private/arr-i.rkt:534:0 filter [57] 51.1% ------------------------------------------------------------------------ bf-complex-neg [45] 2.3% bf-complex-div [44] 11.8% bf* [50] 16.8% bf-complex-exp [43] 67.2% [54] 117492(84.1%) 77362(55.4%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfadd [58] 24.2% bfneg [59] 10.0% ------------------------------------------------------------------------ ...higher-order.rkt:361:33 [51]100.0% [55] 1274(0.9%) 964(0.7%) ??? ...cts/racket/contract/private/list.rkt:209:10 ??? [61] 24.3% ------------------------------------------------------------------------ iterate-egraph!7 [52] 100.0% [56] 626(0.4%) 0(0.0%) one-iter ...bie/develop/src/core/simplify.rkt:66:0 for-loop [60] 100.0% ------------------------------------------------------------------------ return/no-unsupplied [53] 100.0% [57] 312(0.2%) 312(0.2%) filter ...t/collects/racket/private/list.rkt:256:2 ------------------------------------------------------------------------ ??? [54] 100.0% [58] 28424(20.3%) 648(0.5%) bfadd ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [62] 79.1% ??? [63] 18.6% ------------------------------------------------------------------------ ??? [54] 100.0% [59] 11706(8.4%) 0(0.0%) bfneg ...-lib/math/private/bigfloat/mpfr.rkt:618:4 new-mpfr [62] 78.4% ??? [63] 21.6% ------------------------------------------------------------------------ for-loop [60] 50.0% one-iter [56] 50.0% [60] 626(0.4%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:76:12 for-loop [60] 50.0% match-e [64] 50.0% ------------------------------------------------------------------------ ??? [55] 100.0% [61] 310(0.2%) 310(0.2%) ??? ...ects/racket/contract/private/guts.rkt:746:8 ------------------------------------------------------------------------ sig+exp->bigfloat [38] 2.4% bf [7] 19.1% bfneg [59] 22.7% bfadd [58] 55.7% [62] 40376(28.9%) 40376(28.9%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ------------------------------------------------------------------------ bf [7] 11.4% bfneg [59] 28.7% bfadd [58] 59.9% [63] 8824(6.3%) 8824(6.3%) ??? /home/p92/racket/collects/ffi/unsafe.rkt:898:4 ------------------------------------------------------------------------ for-loop [60] 100.0% [64] 626(0.4%) 0(0.0%) match-e ...herbie/develop/src/core/ematch.rkt:46:0 make-sequence [65] 53.0% custom-in-set [66] 47.0% ------------------------------------------------------------------------ match-e [64] 100.0% [65] 332(0.2%) 332(0.2%) make-sequence ...ects/racket/private/for.rkt:528:2 ------------------------------------------------------------------------ match-e [64] 100.0% [66] 294(0.2%) 294(0.2%) custom-in-set ...acket/private/set-types.rkt:577:0 ------------------------------------------------------------------------