Profiling results ----------------- Total cpu time observed: 29432ms (out of 30156ms) Number of samples taken: 68 (once every 433ms) ================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ================================================================== ??? [3] 100.0% [1] 29130(99.0%) 0(0.0%) profile-thunk16 ...t/pkgs/profile-lib/main.rkt:9:0 run [2] 100.0% ------------------------------------------------------------------ profile-thunk16 [1] 99.0% [2] 29432(100.0%) 0(0.0%) run ...share/racket/pkgs/profile-lib/main.rkt:39:2 ??? [3] 100.0% ------------------------------------------------------------------ run [2] 50.5% [3] 29432(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 49.5% loop [5] 30.1% prepare-points [6] 15.7% run-improve50 [4] 4.7% ------------------------------------------------------------------ ??? [3] 100.0% [4] 2772(9.4%) 0(0.0%) run-improve50 ...lt-testing/src/mainloop.rkt:234:0 for-loop [7] 51.2% loop [5] 25.0% setup-prog [8] 12.1% prepare-points [6] 11.7% ------------------------------------------------------------------ run-improve50 [4] 3.8% ??? [3] 96.2% [5] 18130(61.6%) 0(0.0%) loop ...es/herbie/alt-testing/src/points.rkt:238:2 loop [12] 39.4% loop [9] 31.5% make-exacts* [13] 24.5% for-loop [10] 4.6% ------------------------------------------------------------------ run-improve50 [4] 3.4% ??? [3] 96.6% [6] 9546(32.4%) 0(0.0%) prepare-points ...alt-testing/src/points.rkt:214:0 loop [9] 30.8% make-exacts* [13] 27.6% loop [12] 26.8% for-loop [10] 14.8% ------------------------------------------------------------------ run-improve50 [4] 100.0% [7] 1420(4.8%) 0(0.0%) for-loop ...ie/alt-testing/src/mainloop.rkt:249:10 run-iter! [11] 100.0% ------------------------------------------------------------------ run-improve50 [4] 100.0% [8] 336(1.1%) 0(0.0%) setup-prog ...herbie/alt-testing/src/glue.rkt:40:0 ??? [27] 100.0% ------------------------------------------------------------------ prepare-points [6] 34.0% loop [5] 66.0% [9] 8658(29.4%) 0(0.0%) loop ...es/herbie/alt-testing/src/points.rkt:158:2 make-exacts* [13] 53.2% loop [12] 46.8% ------------------------------------------------------------------ loop [5] 37.1% prepare-points [6] 62.9% [10] 2242(7.6%) 384(1.3%) for-loop ...rbie/alt-testing/src/points.rkt:255:26 ??? [14] 82.9% ------------------------------------------------------------------ for-loop [7] 100.0% [11] 1420(4.8%) 0(0.0%) run-iter! ...ie/alt-testing/src/mainloop.rkt:215:0 choose-best-alt! [15] 100.0% ------------------------------------------------------------------ prepare-points [6] 18.6% loop [9] 29.5% loop [5] 52.0% [12] 13760(46.8%) 0(0.0%) loop ...es/herbie/alt-testing/src/points.rkt:141:4 loop [16] 100.0% ------------------------------------------------------------------ prepare-points [6] 22.6% loop [5] 38.0% loop [9] 39.4% [13] 11674(39.7%) 0(0.0%) make-exacts* ...e/alt-testing/src/points.rkt:138:0 loop [16] 96.9% eval-prog [17] 3.1% ------------------------------------------------------------------ for-loop [10] 100.0% [14] 1858(6.3%) 0(0.0%) ??? ...ies/herbie/alt-testing/src/points.rkt:230:7 ??? [19] 100.0% ------------------------------------------------------------------ run-iter! [11] 100.0% [15] 1420(4.8%) 0(0.0%) choose-best-alt! ...testing/src/mainloop.rkt:115:0 ??? [27] 100.0% ------------------------------------------------------------------ loop [12] 2.2% make-exacts* [13] 3.9% loop [16] 93.9% [16] 25068(85.2%) 360(1.2%) loop ...cket/collects/racket/private/map.rkt:25:19 loop [16] 93.9% ??? [18] 4.7% ??? [19] 1.4% ??? [20] 0.0% ------------------------------------------------------------------ make-exacts* [13] 100.0% [17] 366(1.2%) 0(0.0%) eval-prog ...ie/alt-testing/src/programs.rkt:150:0 ??? [19] 100.0% ------------------------------------------------------------------ loop [16] 100.0% [18] 21732(73.8%) 706(2.4%) ??? ...s/herbie/alt-testing/src/programs.rkt:156:4 ??? [21] 61.1% ??? [22] 35.6% ------------------------------------------------------------------ eval-prog [17] 7.5% ??? [14] 38.3% loop [16] 54.2% [19] 4856(16.5%) 348(1.2%) ??? ...tract/private/arrow-higher-order.rkt:342:33 ->flonum [23] 85.3% ->bf [24] 7.5% ------------------------------------------------------------------ loop [16] 100.0% [20] 344(1.2%) 344(1.2%) ??? ...tract/private/arrow-higher-order.rkt:145:21 ------------------------------------------------------------------ ??? [18] 100.0% [21] 13288(45.1%) 2314(7.9%) ??? ...acket/collects/racket/private/kw.rkt:444:14 ??? [25] 79.6% parse-loop145 [29] 3.0% ------------------------------------------------------------------ ??? [18] 100.0% [22] 7738(26.3%) 766(2.6%) ??? ...contract/private/../../private/kw.rkt:761:9 real->bigfloat [26] 90.1% ------------------------------------------------------------------ ??? [19] 100.0% [23] 4142(14.1%) 3048(10.4%) ->flonum ...s/racket/private/norm-define.rkt:53:83 ??? [28] 9.7% return/no-unsupplied [30] 8.4% ??? [31] 8.3% ------------------------------------------------------------------ ??? [19] 100.0% [24] 366(1.2%) 0(0.0%) ->bf ...s/herbie/alt-testing/src/programs.rkt:53:0 ??? [27] 100.0% ------------------------------------------------------------------ ??? [21] 100.0% [25] 10572(35.9%) 3164(10.8%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfexpt [32] 70.1% ------------------------------------------------------------------ ??? [22] 100.0% [26] 6972(23.7%) 5352(18.2%) real->bigfloat .../private/bigfloat/mpfr.rkt:451:0 ??? [35] 23.2% ------------------------------------------------------------------ setup-prog [8] 15.8% ->bf [24] 17.2% choose-best-alt! [15] 66.9% [27] 2122(7.2%) 336(1.1%) ??? ...contract/private/arrow-val-first.rkt:357:18 atab-pick-alt8 [33] 66.9% hash-has-key? [34] 17.2% ------------------------------------------------------------------ ->flonum [23] 100.0% [28] 402(1.4%) 402(1.4%) ??? ...-racket/utils/simple-result-arrow.rkt:60:15 ------------------------------------------------------------------ ??? [21] 100.0% [29] 402(1.4%) 402(1.4%) parse-loop145 .../racket/match/compiler.rkt:418:15 ------------------------------------------------------------------ ->flonum [23] 100.0% [30] 348(1.2%) 348(1.2%) return/no-unsupplied ...ct/private/arr-i.rkt:533:0 ------------------------------------------------------------------ ->flonum [23] 100.0% [31] 344(1.2%) 344(1.2%) ??? ...racket/collects/racket/private/kw.rkt:761:9 ------------------------------------------------------------------ ??? [25] 100.0% [32] 7408(25.2%) 4872(16.6%) bfexpt ...lib/math/private/bigfloat/mpfr.rkt:819:4 ??? [35] 34.2% ------------------------------------------------------------------ ??? [27] 100.0% [33] 1420(4.8%) 0(0.0%) atab-pick-alt8 ...ting/src/core/alt-table.rkt:61:0 for-loop [36] 100.0% ------------------------------------------------------------------ ??? [27] 100.0% [34] 366(1.2%) 0(0.0%) hash-has-key? ...et/private/more-scheme.rkt:368:13 ??? [37] 100.0% ------------------------------------------------------------------ real->bigfloat [26] 39.0% bfexpt [32] 61.0% [35] 4156(14.1%) 4156(14.1%) ??? .../share/racket/collects/ffi/unsafe.rkt:861:4 ------------------------------------------------------------------ atab-pick-alt8 [33] 100.0% [36] 1420(4.8%) 1420(4.8%) for-loop ...lt-testing/src/core/alt-table.rkt:88:2 ------------------------------------------------------------------ hash-has-key? [34] 100.0% [37] 366(1.2%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:737:4 arrow-higher-order:lnp [38]100.0% ------------------------------------------------------------------ ??? [37] 100.0% [38] 366(1.2%) 366(1.2%) arrow-higher-order:lnp ...w-higher-order.rkt:597:7 ------------------------------------------------------------------