Profiling results ----------------- Total cpu time observed: 5740ms (out of 6107ms) Number of samples taken: 20 (once every 287ms) ================================================================ Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ================================================================ [1] 5740(100.0%) 0(0.0%) ??? ...are/racket/collects/racket/engine.rkt:42:24 profile-thunk [2] 100.0% ---------------------------------------------------------------- ??? [1] 100.0% [2] 5740(100.0%) 0(0.0%) profile-thunk ...ket/pkgs/profile-lib/main.rkt:9:0 ??? [3] 100.0% ---------------------------------------------------------------- profile-thunk [2] 100.0% [3] 5740(100.0%) 0(0.0%) ??? ...hare/racket/pkgs/profile-lib/main.rkt:40:10 ??? [4] 100.0% ---------------------------------------------------------------- ??? [3] 100.0% [4] 5740(100.0%) 0(0.0%) ??? ...ies/herbie/alt-testing/src/sandbox.rkt:49:6 prepare-points [5] 90.5% run-improve [6] 9.5% ---------------------------------------------------------------- ??? [4] 100.0% [5] 5194(90.5%) 0(0.0%) prepare-points ...alt-testing/src/points.rkt:214:0 loop [12] 51.6% make-exacts [7] 37.4% filter-points [8] 5.7% ??? [9] 5.3% ---------------------------------------------------------------- ??? [4] 100.0% [6] 546(9.5%) 0(0.0%) run-improve .../alt-testing/src/mainloop.rkt:225:0 run-iter! [10] 50.1% setup-prog! [11] 49.9% ---------------------------------------------------------------- prepare-points [5] 100.0% [7] 1942(33.8%) 0(0.0%) make-exacts ...ie/alt-testing/src/points.rkt:156:0 loop [12] 85.9% make-exacts* [14] 14.1% ---------------------------------------------------------------- prepare-points [5] 100.0% [8] 297(5.2%) 0(0.0%) filter-points .../alt-testing/src/points.rkt:171:0 make-sequence [13] 100.0% ---------------------------------------------------------------- prepare-points [5] 100.0% [9] 277(4.8%) 0(0.0%) ??? ...ies/herbie/alt-testing/src/points.rkt:230:7 ??? [21] 100.0% ---------------------------------------------------------------- run-improve [6] 100.0% [10] 274(4.8%) 0(0.0%) run-iter! ...ie/alt-testing/src/mainloop.rkt:206:0 choose-best-alt! [15] 100.0% ---------------------------------------------------------------- run-improve [6] 100.0% [11] 272(4.7%) 0(0.0%) setup-prog! ...e/alt-testing/src/mainloop.rkt:72:0 setup-prog [16] 100.0% ---------------------------------------------------------------- make-exacts [7] 38.4% prepare-points [5] 61.6% [12] 4347(75.7%) 0(0.0%) loop ...es/herbie/alt-testing/src/points.rkt:141:4 loop [17] 100.0% ---------------------------------------------------------------- filter-points [8] 100.0% [13] 297(5.2%) 297(5.2%) make-sequence ...ects/racket/private/for.rkt:557:2 ---------------------------------------------------------------- make-exacts [7] 100.0% [14] 274(4.8%) 0(0.0%) make-exacts* ...e/alt-testing/src/points.rkt:138:0 eval-prog [35] 100.0% ---------------------------------------------------------------- run-iter! [10] 100.0% [15] 274(4.8%) 0(0.0%) choose-best-alt! ...testing/src/mainloop.rkt:106:0 ??? [18] 100.0% ---------------------------------------------------------------- setup-prog! [11] 100.0% [16] 272(4.7%) 0(0.0%) setup-prog ...herbie/alt-testing/src/glue.rkt:40:0 ??? [18] 100.0% ---------------------------------------------------------------- ??? [34] 0.1% loop [12] 1.4% ??? [46] 5.9% loop [17] 92.6% [17] 4620(80.5%) 0(0.0%) loop .../private/map.rkt:40:19 loop [17] 92.6% hash-ref! [43] 5.9% ??? [34] 1.4% ??? [21] 0.1% ---------------------------------------------------------------- setup-prog [16] 49.9% choose-best-alt! [15] 50.1% [18] 546(9.5%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:486:18 atab-pick-alt [19] 50.1% make-alt-table [20] 49.9% ---------------------------------------------------------------- ??? [18] 100.0% [19] 274(4.8%) 0(0.0%) atab-pick-alt ...sting/src/core/alt-table.rkt:64:0 ??? [21] 100.0% ---------------------------------------------------------------- ??? [18] 100.0% [20] 272(4.7%) 0(0.0%) make-alt-table ...ting/src/core/alt-table.rkt:34:0 errors [33] 100.0% ---------------------------------------------------------------- atab-pick-alt [19] 16.0% ??? [9] 16.2% loop [17] 67.8% [21] 1710(29.8%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:375:33 real->bigfloat [22] 51.3% ->flonum [23] 16.5% sample-multi-bounded [24] 16.2% best-alt [25] 16.0% ---------------------------------------------------------------- ??? [21] 100.0% [22] 876(15.3%) 0(0.0%) real->bigfloat .../private/bigfloat/mpfr.rkt:470:0 new-mpfr [26] 63.8% ??? [27] 36.2% ---------------------------------------------------------------- ??? [21] 100.0% [23] 283(4.9%) 0(0.0%) ->flonum ...rbie/alt-testing/src/programs.rkt:37:0 return/no-unsupplied [28] 100.0% ---------------------------------------------------------------- ??? [21] 100.0% [24] 277(4.8%) 277(4.8%) sample-multi-bounded ...esting/src/points.rkt:44:0 ---------------------------------------------------------------- ??? [21] 100.0% [25] 274(4.8%) 0(0.0%) best-alt ...s/herbie/alt-testing/src/glue.rkt:71:0 argmins [29] 100.0% ---------------------------------------------------------------- real->bigfloat [22] 100.0% [26] 560(9.7%) 560(9.7%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:344:0 ---------------------------------------------------------------- real->bigfloat [22] 100.0% [27] 317(5.5%) 0(0.0%) ??? .../share/racket/collects/ffi/unsafe.rkt:959:4 assq [30] 100.0% ---------------------------------------------------------------- ->flonum [23] 100.0% [28] 283(4.9%) 0(0.0%) return/no-unsupplied ...ct/private/arr-i.rkt:566:0 filter [31] 100.0% ---------------------------------------------------------------- best-alt [25] 100.0% [29] 274(4.8%) 0(0.0%) argmins ...herbie/alt-testing/src/common.rkt:152:0 composed [32] 100.0% ---------------------------------------------------------------- ??? [27] 100.0% [30] 317(5.5%) 317(5.5%) assq ...et/collects/racket/private/list.rkt:187:13 ---------------------------------------------------------------- return/no-unsupplied [28] 100.0% [31] 283(4.9%) 283(4.9%) filter ...t/collects/racket/private/list.rkt:275:2 ---------------------------------------------------------------- argmins [29] 100.0% [32] 274(4.8%) 0(0.0%) composed ...ollects/racket/private/list.rkt:340:16 errors [33] 100.0% ---------------------------------------------------------------- make-alt-table [20] 49.9% composed [32] 50.1% [33] 546(9.5%) 0(0.0%) errors .../herbie/alt-testing/src/points.rkt:271:0 eval-prog [35] 50.1% ??? [34] 49.9% ---------------------------------------------------------------- errors [33] 6.3% loop [17] 93.7% [34] 4336(75.5%) 2285(39.8%) ??? ...s/herbie/alt-testing/src/programs.rkt:156:4 loop [17] 20.2% ??? [36] 13.7% bf* [37] 7.0% ??? [38] 6.4% ---------------------------------------------------------------- errors [33] 50.0% make-exacts* [14] 50.0% [35] 547(9.5%) 274(4.8%) eval-prog ...ie/alt-testing/src/programs.rkt:150:0 compile [39] 50.0% ---------------------------------------------------------------- ??? [34] 100.0% [36] 594(10.3%) 0(0.0%) ??? .../herbie/alt-testing/src/bigcomplex.rkt:66:2 ^TYPE? [40] 100.0% ---------------------------------------------------------------- ??? [34] 100.0% [37] 304(5.3%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [41] 100.0% ---------------------------------------------------------------- ??? [34] 100.0% [38] 278(4.8%) 0(0.0%) ??? ...ects/racket/contract/private/prop.rkt:567:4 ??? [42] 100.0% ---------------------------------------------------------------- eval-prog [35] 100.0% [39] 274(4.8%) 0(0.0%) compile ...rbie/alt-testing/src/programs.rkt:186:0 hash-ref! [43] 100.0% ---------------------------------------------------------------- ??? [36] 100.0% [40] 594(10.3%) 594(10.3%) ^TYPE? .../ffi/unsafe.rkt:1621:20 ---------------------------------------------------------------- bf* [37] 100.0% [41] 304(5.3%) 0(0.0%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [44] 100.0% ---------------------------------------------------------------- ??? [38] 100.0% [42] 278(4.8%) 278(4.8%) ??? ...ects/racket/contract/private/orc.rkt:112:15 ---------------------------------------------------------------- loop [17] 50.0% compile [39] 50.0% [43] 274(4.8%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:377:2 ??? [46] 50.0% bigfloat-hash [45] 50.0% ---------------------------------------------------------------- ??? [41] 100.0% [44] 304(5.3%) 304(5.3%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:846:4 ---------------------------------------------------------------- hash-ref! [43] 100.0% [45] 274(4.8%) 0(0.0%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:168:0 bfcanonicalize [47] 100.0% ---------------------------------------------------------------- hash-ref! [43] 100.0% [46] 274(4.8%) 0(0.0%) ??? ...s/herbie/alt-testing/src/programs.rkt:194:5 loop [17] 100.0% ---------------------------------------------------------------- bigfloat-hash [45] 100.0% [47] 274(4.8%) 0(0.0%) bfcanonicalize .../private/bigfloat/mpfr.rkt:158:0 bitwise-first-bit-set [48]100.0% ---------------------------------------------------------------- bfcanonicalize [47] 100.0% [48] 274(4.8%) 274(4.8%) bitwise-first-bit-set ...hmetic/bitwise-6.rkt:35:0 ----------------------------------------------------------------