Profiling results ----------------- Total cpu time observed: 10738ms (out of 11000ms) Number of samples taken: 24 (once every 447ms) ======================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ======================================================================== run [8] 50.0% [1] 10738(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [3] 50.0% prepare-points [4] 37.8% loop [5] 10.8% run-improve50 [6] 1.5% ------------------------------------------------------------------------ ??? [37] 100.0% [2] 300(2.8%) 0(0.0%) ??? ...cts/racket/contract/private/list.rkt:187:10 for-loop [7] 100.0% ------------------------------------------------------------------------ ??? [1] 100.0% [3] 10738(100.0%) 0(0.0%) profile-thunk16 ...t/pkgs/profile-lib/main.rkt:9:0 run [8] 100.0% ------------------------------------------------------------------------ ??? [1] 100.0% [4] 8116(75.6%) 0(0.0%) prepare-points ...alt-testing/src/points.rkt:214:0 loop [12] 68.3% loop [9] 16.6% make-exacts* [13] 15.1% ------------------------------------------------------------------------ ??? [1] 100.0% [5] 2310(21.5%) 0(0.0%) loop ...es/herbie/alt-testing/src/points.rkt:238:2 loop [12] 44.0% make-exacts* [13] 26.1% for-loop [10] 16.4% loop [9] 13.6% ------------------------------------------------------------------------ ??? [1] 100.0% [6] 312(2.9%) 0(0.0%) run-improve50 ...lt-testing/src/mainloop.rkt:225:0 for-loop [11] 100.0% ------------------------------------------------------------------------ ??? [2] 100.0% [7] 300(2.8%) 0(0.0%) for-loop ...acket/contract/private/list.rkt:190:15 ??? [37] 100.0% ------------------------------------------------------------------------ profile-thunk16 [3] 100.0% [8] 10738(100.0%) 0(0.0%) run ...share/racket/pkgs/profile-lib/main.rkt:39:2 ??? [1] 100.0% ------------------------------------------------------------------------ loop [5] 18.9% prepare-points [4] 81.1% [9] 1662(15.5%) 0(0.0%) loop ...es/herbie/alt-testing/src/points.rkt:158:2 make-exacts* [13] 59.2% loop [12] 40.8% ------------------------------------------------------------------------ loop [5] 100.0% [10] 378(3.5%) 0(0.0%) for-loop ...rbie/alt-testing/src/points.rkt:255:26 ??? [14] 100.0% ------------------------------------------------------------------------ run-improve50 [6] 100.0% [11] 312(2.9%) 0(0.0%) for-loop ...ie/alt-testing/src/mainloop.rkt:240:10 run-iter! [15] 100.0% ------------------------------------------------------------------------ loop [9] 9.4% loop [5] 14.0% prepare-points [4] 76.6% [12] 7238(67.4%) 0(0.0%) loop ...es/herbie/alt-testing/src/points.rkt:141:4 loop [16] 100.0% ------------------------------------------------------------------------ loop [5] 21.4% loop [9] 35.0% prepare-points [4] 43.6% [13] 2810(26.2%) 0(0.0%) make-exacts* ...e/alt-testing/src/points.rkt:138:0 loop [16] 89.3% eval-prog [18] 10.7% ------------------------------------------------------------------------ for-loop [10] 100.0% [14] 378(3.5%) 0(0.0%) ??? ...ies/herbie/alt-testing/src/points.rkt:230:7 ??? [20] 100.0% ------------------------------------------------------------------------ for-loop [11] 100.0% [15] 312(2.9%) 0(0.0%) run-iter! ...ie/alt-testing/src/mainloop.rkt:206:0 choose-best-alt! [17] 100.0% ------------------------------------------------------------------------ make-exacts* [13] 0.2% loop [12] 0.7% loop [16] 99.1% [16] 9748(90.8%) 314(2.9%) loop ...cket/collects/racket/private/map.rkt:25:19 loop [16] 99.1% ??? [19] 0.8% ??? [20] 0.1% ------------------------------------------------------------------------ run-iter! [15] 100.0% [17] 312(2.9%) 0(0.0%) choose-best-alt! ...testing/src/mainloop.rkt:106:0 ??? [21] 100.0% ------------------------------------------------------------------------ make-exacts* [13] 100.0% [18] 300(2.8%) 0(0.0%) eval-prog ...ie/alt-testing/src/programs.rkt:150:0 ??? [20] 100.0% ------------------------------------------------------------------------ loop [16] 100.0% [19] 6398(59.6%) 0(0.0%) ??? ...s/herbie/alt-testing/src/programs.rkt:156:4 ??? [22] 65.3% ??? [24] 34.7% ------------------------------------------------------------------------ eval-prog [18] 8.1% ??? [14] 10.2% loop [16] 81.7% [20] 3714(34.6%) 714(6.6%) ??? ...tract/private/arrow-higher-order.rkt:342:33 ->flonum [23] 72.7% ->bf [26] 8.1% ------------------------------------------------------------------------ choose-best-alt! [17] 100.0% [21] 312(2.9%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:357:18 atab-pick-alt8 [25] 100.0% ------------------------------------------------------------------------ ??? [19] 100.0% [22] 4178(38.9%) 1038(9.7%) ??? ...acket/collects/racket/private/kw.rkt:444:14 bf* [27] 67.9% ??? [32] 7.2% ------------------------------------------------------------------------ ??? [20] 100.0% [23] 2700(25.1%) 1944(18.1%) ->flonum ...s/racket/private/norm-define.rkt:53:83 return/no-unsupplied [29] 14.3% ??? [30] 13.7% ------------------------------------------------------------------------ ??? [19] 100.0% [24] 2220(20.7%) 410(3.8%) ??? ...contract/private/../../private/kw.rkt:761:9 real->bigfloat [28] 81.5% ------------------------------------------------------------------------ ??? [21] 100.0% [25] 312(2.9%) 0(0.0%) atab-pick-alt8 ...ting/src/core/alt-table.rkt:64:0 write-json* [31] 100.0% ------------------------------------------------------------------------ ??? [20] 100.0% [26] 300(2.8%) 0(0.0%) ->bf ...s/herbie/alt-testing/src/programs.rkt:53:0 ...sting/src/common.rkt:47:2 [33]100.0% ------------------------------------------------------------------------ ??? [22] 100.0% [27] 2838(26.4%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [34] 100.0% ------------------------------------------------------------------------ ??? [24] 100.0% [28] 1810(16.9%) 1810(16.9%) real->bigfloat .../private/bigfloat/mpfr.rkt:451:0 ------------------------------------------------------------------------ ->flonum [23] 100.0% [29] 386(3.6%) 386(3.6%) return/no-unsupplied ...ct/private/arr-i.rkt:533:0 ------------------------------------------------------------------------ ->flonum [23] 100.0% [30] 370(3.4%) 0(0.0%) ??? ...-racket/utils/simple-result-arrow.rkt:60:15 ??? [42] 100.0% ------------------------------------------------------------------------ atab-pick-alt8 [25] 100.0% [31] 312(2.9%) 0(0.0%) write-json* .../racket/collects/json/main.rkt:69:0 loop [35] 100.0% ------------------------------------------------------------------------ ??? [22] 100.0% [32] 302(2.8%) 0(0.0%) ??? ...ects/racket/contract/private/prop.rkt:493:4 ??? [36] 100.0% ------------------------------------------------------------------------ ->bf [26] 100.0% [33] 300(2.8%) 0(0.0%) ...sting/src/common.rkt:47:2 ...e/arr-i.rkt:906:19 ??? [37] 100.0% ------------------------------------------------------------------------ bf* [27] 100.0% [34] 2838(26.4%) 0(0.0%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [38] 100.0% ------------------------------------------------------------------------ for-loop [39] 33.3% for-loop [40] 33.3% write-json* [31] 33.3% [35] 312(2.9%) 312(2.9%) loop .../share/racket/collects/json/main.rkt:103:2 for-loop [40] 33.3% for-loop [39] 33.3% ------------------------------------------------------------------------ ??? [32] 100.0% [36] 302(2.8%) 302(2.8%) ??? ...lects/racket/contract/private/orc.rkt:83:14 ------------------------------------------------------------------------ for-loop [7] 50.0% ...sting/src/common.rkt:47:2 [33] 50.0% [37] 300(2.8%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:342:4 ??? [2] 50.0% ??? [41] 50.0% ------------------------------------------------------------------------ ??? [34] 100.0% [38] 2838(26.4%) 2132(19.9%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:819:4 ??? [42] 24.9% ------------------------------------------------------------------------ loop [35] 100.0% [39] 312(2.9%) 0(0.0%) for-loop ...e/racket/collects/json/main.rkt:113:13 loop [35] 100.0% ------------------------------------------------------------------------ loop [35] 100.0% [40] 312(2.9%) 0(0.0%) for-loop ...e/racket/collects/json/main.rkt:118:11 loop [35] 100.0% ------------------------------------------------------------------------ ??? [37] 100.0% [41] 300(2.8%) 0(0.0%) ??? ...ects/racket/contract/private/guts.rkt:644:8 contract? [43] 100.0% ------------------------------------------------------------------------ ??? [30] 34.4% bfmul [38] 65.6% [42] 1076(10.0%) 1076(10.0%) ??? .../share/racket/collects/ffi/unsafe.rkt:861:4 ------------------------------------------------------------------------ ??? [41] 100.0% [43] 300(2.8%) 0(0.0%) contract? ...acket/contract/private/guts.rkt:109:0 coerce-contract/f4 [44] 100.0% ------------------------------------------------------------------------ contract? [43] 100.0% [44] 300(2.8%) 300(2.8%) coerce-contract/f4 ...tract/private/guts.rkt:299:0 ------------------------------------------------------------------------