Profiling results ----------------- Total cpu time observed: 115544ms (out of 116772ms) Number of samples taken: 310 (once every 373ms) (Hiding functions with self<1.0% and local<2.0%: 2 of 56 hidden) ==================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ==================================================================== ??? [6] 100.0% [1] 114882(99.4%) 0(0.0%) profile-thunk16 ...t/pkgs/profile-lib/main.rkt:9:0 run [3] 100.0% -------------------------------------------------------------------- loop [15] 0.3% for-loop [34] 0.6% loop [38] 99.1% [2] 107324(92.9%) 964(0.8%) ??? ...tlies/herbie/develop/src/programs.rkt:156:4 ??? [4] 89.4% ??? [5] 9.7% -------------------------------------------------------------------- profile-thunk16 [1] 99.7% [3] 115204(99.7%) 0(0.0%) run ...share/racket/pkgs/profile-lib/main.rkt:39:2 ??? [6] 100.0% -------------------------------------------------------------------- ??? [2] 100.0% [4] 95908(83.0%) 3400(2.9%) ??? ...acket/collects/racket/private/kw.rkt:444:14 ??? [45] 73.7% bffmod [39] 16.8% bf* [42] 3.4% ??? [8] 1.4% parse-loop12 [37] 0.4% -------------------------------------------------------------------- ??? [2] 100.0% [5] 10452(9.0%) 996(0.9%) ??? ...contract/private/../../private/kw.rkt:761:9 real->bigfloat [7] 90.5% -------------------------------------------------------------------- run [3] 50.1% [6] 115204(99.7%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 49.9% loop [10] 48.0% run-improve50 [9] 2.1% -------------------------------------------------------------------- ??? [5] 100.0% [7] 9456(8.2%) 8788(7.6%) real->bigfloat .../private/bigfloat/mpfr.rkt:451:0 ??? [54] 7.1% -------------------------------------------------------------------- ??? [4] 100.0% [8] 1322(1.1%) 1322(1.1%) ??? ...rivate/unconstrained-domain-arrow.rkt:40:13 -------------------------------------------------------------------- ??? [6] 100.0% [9] 4600(4.0%) 0(0.0%) run-improve50 ...ie/develop/src/mainloop.rkt:234:0 loop [10] 71.3% for-loop [11] 21.6% ??? [22] 7.1% -------------------------------------------------------------------- run-improve50 [9] 2.9% ??? [6] 97.1% [10] 113884(98.6%) 322(0.3%) loop ...htlies/herbie/develop/src/points.rkt:205:2 loop [12] 40.3% make-exacts* [16] 30.7% loop [15] 27.9% for-loop [13] 0.9% -------------------------------------------------------------------- run-improve50 [9] 100.0% [11] 992(0.9%) 0(0.0%) for-loop ...herbie/develop/src/mainloop.rkt:249:10 run-iter! [14] 100.0% -------------------------------------------------------------------- loop [10] 100.0% [12] 45896(39.7%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:158:2 loop [15] 54.1% make-exacts* [16] 45.9% -------------------------------------------------------------------- loop [10] 100.0% [13] 1006(0.9%) 0(0.0%) for-loop ...s/herbie/develop/src/points.rkt:222:26 for-loop [17] 100.0% -------------------------------------------------------------------- for-loop [11] 100.0% [14] 992(0.9%) 0(0.0%) run-iter! ...herbie/develop/src/mainloop.rkt:215:0 finalize-iter! [18] 36.3% gen-rewrites! [19] 33.5% localize-error [20] 30.2% -------------------------------------------------------------------- loop [12] 43.9% loop [10] 56.1% [15] 56572(49.0%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:141:4 loop [38] 99.5% ??? [2] 0.5% -------------------------------------------------------------------- loop [12] 37.6% loop [10] 62.4% [16] 55984(48.5%) 0(0.0%) make-exacts* ...erbie/develop/src/points.rkt:138:0 loop [38] 98.3% eval-prog [21] 1.7% -------------------------------------------------------------------- for-loop [13] 100.0% [17] 1006(0.9%) 0(0.0%) for-loop ...s/herbie/develop/src/points.rkt:223:28 ??? [40] 100.0% -------------------------------------------------------------------- run-iter! [14] 100.0% [18] 360(0.3%) 0(0.0%) finalize-iter! ...e/develop/src/mainloop.rkt:169:0 ??? [22] 100.0% -------------------------------------------------------------------- run-iter! [14] 100.0% [19] 332(0.3%) 0(0.0%) gen-rewrites! ...ie/develop/src/mainloop.rkt:142:0 for-loop [23] 100.0% -------------------------------------------------------------------- run-iter! [14] 100.0% [20] 300(0.3%) 0(0.0%) localize-error ...velop/src/core/localize.rkt:47:0 hash-ref! [36] 100.0% -------------------------------------------------------------------- make-exacts* [16] 100.0% [21] 970(0.8%) 670(0.6%) eval-prog ...herbie/develop/src/programs.rkt:150:0 ??? [27] 30.9% -------------------------------------------------------------------- run-improve50 [9] 47.7% finalize-iter! [18] 52.3% [22] 688(0.6%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:357:18 for-loop [24] 52.3% atab-add-altns [26] 47.7% -------------------------------------------------------------------- gen-rewrites! [19] 100.0% [23] 332(0.3%) 0(0.0%) for-loop ...herbie/develop/src/mainloop.rkt:147:11 ??? [25] 100.0% -------------------------------------------------------------------- ??? [22] 100.0% [24] 360(0.3%) 0(0.0%) for-loop ...ie/develop/src/core/alt-table.rkt:51:2 atab-add-altn [28] 100.0% -------------------------------------------------------------------- for-loop [23] 100.0% [25] 332(0.3%) 0(0.0%) ??? ...ies/herbie/develop/src/alternative.rkt:88:0 rewriter [29] 100.0% -------------------------------------------------------------------- ??? [22] 100.0% [26] 328(0.3%) 0(0.0%) atab-add-altns ...elop/src/core/alt-table.rkt:50:0 atab-add-altn [28] 100.0% -------------------------------------------------------------------- eval-prog [21] 100.0% [27] 300(0.3%) 300(0.3%) ??? ...tract/private/arrow-higher-order.rkt:346:33 -------------------------------------------------------------------- atab-add-altns [26] 47.7% for-loop [24] 52.3% [28] 688(0.6%) 0(0.0%) atab-add-altn ...elop/src/core/alt-table.rkt:208:0 best-and-tied-at-points [30]100.0% -------------------------------------------------------------------- ??? [25] 100.0% [29] 332(0.3%) 0(0.0%) rewriter ...bie/develop/src/core/matcher.rkt:131:2 f6 [31] 100.0% -------------------------------------------------------------------- atab-add-altn [28] 100.0% [30] 688(0.6%) 0(0.0%) best-and-tied-at-points ...ore/alt-table.rkt:122:0 errors [32] 100.0% -------------------------------------------------------------------- f6 [31] 26.3% rewriter [29] 26.3% hash-ref! [36] 47.5% [31] 632(0.5%) 0(0.0%) f6 ...et/collects/racket/match/compiler.rkt:507:40 map [33] 47.5% f6 [31] 26.3% sample-multi-bounded [43] 26.3% -------------------------------------------------------------------- best-and-tied-at-points [30]100.0% [32] 688(0.6%) 0(0.0%) errors ...lies/herbie/develop/src/points.rkt:241:0 for-loop [34] 100.0% -------------------------------------------------------------------- f6 [31] 100.0% [33] 300(0.3%) 0(0.0%) map ...acket/collects/racket/private/map.rkt:20:13 ??? [35] 100.0% -------------------------------------------------------------------- errors [32] 100.0% [34] 688(0.6%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:244:4 ??? [2] 100.0% -------------------------------------------------------------------- map [33] 50.0% loop [38] 50.0% [35] 300(0.3%) 0(0.0%) ??? ...ket/collects/racket/private/list.rkt:323:18 hash-ref! [36] 100.0% -------------------------------------------------------------------- localize-error [20] 33.3% ??? [35] 66.7% [36] 300(0.3%) 0(0.0%) hash-ref! ...racket/private/more-scheme.rkt:373:13 parse-loop12 [37] 66.7% f6 [31] 33.3% -------------------------------------------------------------------- hash-ref! [36] 46.2% ??? [4] 53.8% [37] 650(0.6%) 350(0.3%) parse-loop12 ...s/racket/match/compiler.rkt:418:15 loop [38] 46.2% -------------------------------------------------------------------- parse-loop12 [37] 0.0% loop [15] 1.1% make-exacts* [16] 2.2% loop [38] 96.6% [38] 111918(96.9%) 334(0.3%) loop ...cket/collects/racket/private/map.rkt:25:19 loop [38] 96.6% ??? [2] 3.3% ??? [40] 0.1% ??? [35] 0.0% bffmod [39] 0.0% ??? [41] 0.0% -------------------------------------------------------------------- loop [38] 1.8% ??? [4] 98.2% [39] 16420(14.2%) 1326(1.1%) bffmod ...rbie/develop/src/syntax/syntax.rkt:332:0 bf* [42] 46.0% ??? [45] 45.9% -------------------------------------------------------------------- for-loop [17] 17.9% loop [38] 82.1% [40] 5606(4.9%) 646(0.6%) ??? ...tract/private/arrow-higher-order.rkt:342:33 sample-multi-bounded [43] 88.5% -------------------------------------------------------------------- loop [38] 100.0% [41] 356(0.3%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:145:21 ??? [44] 100.0% -------------------------------------------------------------------- ??? [4] 30.3% bffmod [39] 69.7% [42] 10848(9.4%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [45] 100.0% -------------------------------------------------------------------- f6 [31] 6.3% ??? [40] 93.7% [43] 5292(4.6%) 3536(3.1%) sample-multi-bounded ...vate/norm-define.rkt:53:83 ??? [46] 21.1% ??? [47] 6.3% list-member? [48] 5.9% -------------------------------------------------------------------- ??? [41] 100.0% [44] 356(0.3%) 356(0.3%) ??? ...ects/racket/contract/private/prop.rkt:493:4 -------------------------------------------------------------------- bffmod [39] 8.5% bf* [42] 12.2% ??? [4] 79.4% [45] 89090(77.1%) 63246(54.7%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfsub [49] 19.0% bfasin [50] 8.1% bffloor [51] 1.9% -------------------------------------------------------------------- sample-multi-bounded [43] 100.0% [46] 1114(1.0%) 340(0.3%) ??? ...racket/collects/racket/private/kw.rkt:761:9 ??? [52] 69.5% -------------------------------------------------------------------- sample-multi-bounded [43] 100.0% [47] 332(0.3%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:737:4 ??? [53] 100.0% -------------------------------------------------------------------- sample-multi-bounded [43] 100.0% [48] 310(0.3%) 310(0.3%) list-member? ...llects/racket/private/set.rkt:24:0 -------------------------------------------------------------------- ??? [45] 100.0% [49] 16968(14.7%) 14222(12.3%) bfsub ...-lib/math/private/bigfloat/mpfr.rkt:819:4 ??? [54] 16.2% -------------------------------------------------------------------- ??? [45] 100.0% [50] 7202(6.2%) 5428(4.7%) bfasin ...lib/math/private/bigfloat/mpfr.rkt:616:4 ??? [54] 24.6% -------------------------------------------------------------------- ??? [45] 100.0% [51] 1674(1.4%) 662(0.6%) bffloor ...ib/math/private/bigfloat/mpfr.rkt:714:4 ??? [54] 60.5% -------------------------------------------------------------------- ??? [46] 100.0% [52] 774(0.7%) 774(0.7%) ??? ...s/racket/contract/private/arr-i.rkt:1233:27 -------------------------------------------------------------------- ??? [47] 100.0% [53] 332(0.3%) 332(0.3%) ??? ...private/unconstrained-domain-arrow.rkt:74:4 -------------------------------------------------------------------- real->bigfloat [7] 10.8% bffloor [51] 16.3% bfasin [50] 28.6% bfsub [49] 44.3% [54] 6200(5.4%) 6200(5.4%) ??? .../share/racket/collects/ffi/unsafe.rkt:861:4 --------------------------------------------------------------------