Profiling results ----------------- Total cpu time observed: 11650ms (out of 12496ms) Number of samples taken: 33 (once every 353ms) =============================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee =============================================================== run [7] 50.0% [1] 11650(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [4] 50.0% prepare-points [8] 46.0% run-improve50 [5] 4.0% --------------------------------------------------------------- hash-ref! [23] 100.0% [2] 378(3.2%) 0(0.0%) ??? ...s/herbie/alt-testing/src/programs.rkt:194:5 loop [19] 100.0% --------------------------------------------------------------- ->flonum [26] 100.0% [3] 318(2.7%) 0(0.0%) loop ...ie/alt-testing/src/core/simplify.rkt:237:2 pass [6] 100.0% --------------------------------------------------------------- ??? [1] 100.0% [4] 11650(100.0%) 0(0.0%) profile-thunk16 ...t/pkgs/profile-lib/main.rkt:9:0 run [7] 100.0% --------------------------------------------------------------- ??? [1] 100.0% [5] 934(8.0%) 0(0.0%) run-improve50 ...lt-testing/src/mainloop.rkt:225:0 setup-alt-simplified [9] 66.6% prepare-points [8] 33.4% --------------------------------------------------------------- loop [3] 100.0% [6] 318(2.7%) 0(0.0%) pass ...ie/alt-testing/src/core/simplify.rkt:227:2 for-loop [10] 100.0% --------------------------------------------------------------- profile-thunk16 [4] 100.0% [7] 11650(100.0%) 0(0.0%) run ...share/racket/pkgs/profile-lib/main.rkt:39:2 ??? [1] 100.0% --------------------------------------------------------------- run-improve50 [5] 2.8% ??? [1] 97.2% [8] 11028(94.7%) 0(0.0%) prepare-points ...alt-testing/src/points.rkt:214:0 loop [11] 34.8% make-exacts* [15] 33.9% loop [16] 27.9% for-loop [13] 3.4% --------------------------------------------------------------- run-improve50 [5] 100.0% [9] 622(5.3%) 0(0.0%) setup-alt-simplified ...-testing/src/glue.rkt:49:0 simplify-alt [12] 100.0% --------------------------------------------------------------- pass [6] 100.0% [10] 318(2.7%) 0(0.0%) for-loop ...t-testing/src/core/simplify.rkt:230:29 argmin [14] 100.0% --------------------------------------------------------------- prepare-points [8] 100.0% [11] 3836(32.9%) 0(0.0%) loop ...es/herbie/alt-testing/src/points.rkt:158:2 make-exacts* [15] 63.9% loop [16] 36.1% --------------------------------------------------------------- setup-alt-simplified [9]100.0% [12] 622(5.3%) 0(0.0%) simplify-alt ...rbie/alt-testing/src/glue.rkt:76:0 ->flonum [26] 100.0% --------------------------------------------------------------- prepare-points [8] 100.0% [13] 372(3.2%) 0(0.0%) for-loop ...rbie/alt-testing/src/points.rkt:255:26 ??? [17] 100.0% --------------------------------------------------------------- for-loop [10] 100.0% [14] 318(2.7%) 0(0.0%) argmin ...re/racket/collects/racket/list.rkt:743:0 for-loop [18] 100.0% --------------------------------------------------------------- loop [11] 39.6% prepare-points [8] 60.4% [15] 6194(53.2%) 0(0.0%) make-exacts* ...e/alt-testing/src/points.rkt:138:0 loop [19] 93.9% eval-prog [20] 6.1% --------------------------------------------------------------- loop [11] 31.0% prepare-points [8] 69.0% [16] 4462(38.3%) 0(0.0%) loop ...es/herbie/alt-testing/src/points.rkt:141:4 loop [19] 100.0% --------------------------------------------------------------- for-loop [13] 100.0% [17] 372(3.2%) 0(0.0%) ??? ...ies/herbie/alt-testing/src/points.rkt:230:7 ??? [22] 100.0% --------------------------------------------------------------- argmin [14] 100.0% [18] 318(2.7%) 0(0.0%) for-loop ...bie/alt-testing/src/programs.rkt:210:2 ->flonum [26] 100.0% --------------------------------------------------------------- make-exacts* [15] 0.2% loop [16] 0.2% ??? [2] 3.5% loop [19] 96.1% [19] 10656(91.5%) 0(0.0%) loop ...cket/collects/racket/private/map.rkt:25:19 loop [19] 96.1% hash-ref! [23] 3.5% ??? [21] 0.3% ??? [22] 0.1% --------------------------------------------------------------- make-exacts* [15] 100.0% [20] 378(3.2%) 0(0.0%) eval-prog ...ie/alt-testing/src/programs.rkt:150:0 hash-ref! [23] 100.0% --------------------------------------------------------------- loop [19] 100.0% [21] 9260(79.5%) 0(0.0%) ??? ...s/herbie/alt-testing/src/programs.rkt:156:4 ??? [24] 82.1% ??? [25] 17.9% --------------------------------------------------------------- ??? [17] 26.8% loop [19] 73.2% [22] 1390(11.9%) 372(3.2%) ??? ...tract/private/arrow-higher-order.rkt:342:33 ->flonum [26] 73.2% --------------------------------------------------------------- eval-prog [20] 25.0% loop [19] 75.0% [23] 378(3.2%) 0(0.0%) hash-ref! ...racket/private/more-scheme.rkt:373:13 ??? [2] 75.0% bigfloat-hash [27] 25.0% --------------------------------------------------------------- ??? [21] 100.0% [24] 7600(65.2%) 1818(15.6%) ??? ...acket/collects/racket/private/kw.rkt:444:14 bf* [28] 49.3% ??? [33] 26.8% --------------------------------------------------------------- ??? [21] 100.0% [25] 1660(14.2%) 0(0.0%) ??? ...contract/private/../../private/kw.rkt:761:9 real->bigfloat [29] 100.0% --------------------------------------------------------------- for-loop [18] 6.5% ->flonum [26] 15.7% simplify-alt [12] 15.7% ??? [22] 62.1% [26] 1640(14.1%) 988(8.5%) ->flonum ...s/racket/private/norm-define.rkt:53:83 ??? [31] 21.2% ->flonum [26] 15.7% iterate-egraph!13 [32] 9.3% loop [3] 6.5% --------------------------------------------------------------- hash-ref! [23] 100.0% [27] 378(3.2%) 0(0.0%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:165:0 sig+exp->bigfloat [30] 100.0% --------------------------------------------------------------- ??? [24] 100.0% [28] 3744(32.1%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [33] 100.0% --------------------------------------------------------------- ??? [25] 100.0% [29] 1660(14.2%) 994(8.5%) real->bigfloat .../private/bigfloat/mpfr.rkt:451:0 ??? [39] 40.1% --------------------------------------------------------------- bigfloat-hash [27] 100.0% [30] 378(3.2%) 378(3.2%) sig+exp->bigfloat ...ivate/bigfloat/mpfr.rkt:385:0 --------------------------------------------------------------- ->flonum [26] 100.0% [31] 348(3.0%) 0(0.0%) ??? ...racket/collects/racket/private/kw.rkt:761:9 ??? [34] 100.0% --------------------------------------------------------------- ->flonum [26] 100.0% [32] 304(2.6%) 0(0.0%) iterate-egraph!13 ...g/src/core/simplify.rkt:105:0 one-iter [35] 100.0% --------------------------------------------------------------- ??? [24] 35.2% bf* [28] 64.8% [33] 5782(49.6%) 1436(12.3%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [36] 63.3% bfsqrt [37] 11.9% --------------------------------------------------------------- ??? [31] 100.0% [34] 348(3.0%) 348(3.0%) ??? ...s/racket/contract/private/arr-i.rkt:1233:27 --------------------------------------------------------------- iterate-egraph!13 [32] 100.0% [35] 304(2.6%) 0(0.0%) one-iter ...lt-testing/src/core/simplify.rkt:116:0 for-loop [38] 100.0% --------------------------------------------------------------- ??? [33] 100.0% [36] 3658(31.4%) 2156(18.5%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:819:4 ??? [39] 41.1% --------------------------------------------------------------- ??? [33] 100.0% [37] 688(5.9%) 362(3.1%) bfsqrt ...lib/math/private/bigfloat/mpfr.rkt:616:4 ??? [39] 47.4% --------------------------------------------------------------- for-loop [38] 50.0% one-iter [35] 50.0% [38] 304(2.6%) 0(0.0%) for-loop ...t-testing/src/core/simplify.rkt:126:12 for-loop [38] 50.0% match-e [40] 50.0% --------------------------------------------------------------- bfsqrt [37] 13.1% real->bigfloat [29] 26.7% bfmul [36] 60.2% [39] 2494(21.4%) 2494(21.4%) ??? .../share/racket/collects/ffi/unsafe.rkt:861:4 --------------------------------------------------------------- for-loop [38] 100.0% [40] 304(2.6%) 304(2.6%) match-e ...ie/alt-testing/src/core/ematch.rkt:46:0 ---------------------------------------------------------------