Profiling results ----------------- Total cpu time observed: 12634ms (out of 13196ms) Number of samples taken: 29 (once every 436ms) ====================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ====================================================================== run [5] 50.0% [1] 12634(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [2] 50.0% prepare-points [6] 40.2% run-improve50 [3] 5.3% loop [4] 4.5% ---------------------------------------------------------------------- ??? [1] 100.0% [2] 12634(100.0%) 0(0.0%) profile-thunk16 ...t/pkgs/profile-lib/main.rkt:9:0 run [5] 100.0% ---------------------------------------------------------------------- ??? [1] 100.0% [3] 1350(10.7%) 0(0.0%) run-improve50 ...lt-testing/src/mainloop.rkt:225:0 prepare-points [6] 40.0% setup-alt-simplified [7] 33.9% for-loop [8] 26.1% ---------------------------------------------------------------------- ??? [1] 100.0% [4] 1136(9.0%) 0(0.0%) loop ...es/herbie/alt-testing/src/points.rkt:238:2 make-exacts* [15] 36.3% loop [9] 34.9% loop [14] 28.9% ---------------------------------------------------------------------- profile-thunk16 [2] 100.0% [5] 12634(100.0%) 0(0.0%) run ...share/racket/pkgs/profile-lib/main.rkt:39:2 ??? [1] 100.0% ---------------------------------------------------------------------- run-improve50 [3] 5.1% ??? [1] 94.9% [6] 10688(84.6%) 0(0.0%) prepare-points ...alt-testing/src/points.rkt:214:0 loop [14] 28.7% for-loop [10] 24.9% make-exacts* [15] 22.0% loop [9] 21.5% filter-points [13] 2.9% ---------------------------------------------------------------------- run-improve50 [3] 100.0% [7] 458(3.6%) 0(0.0%) setup-alt-simplified ...-testing/src/glue.rkt:49:0 simplify-alt [11] 100.0% ---------------------------------------------------------------------- run-improve50 [3] 100.0% [8] 352(2.8%) 0(0.0%) for-loop ...ie/alt-testing/src/mainloop.rkt:240:10 run-iter! [12] 100.0% ---------------------------------------------------------------------- loop [4] 14.7% prepare-points [6] 85.3% [9] 2696(21.3%) 0(0.0%) loop ...es/herbie/alt-testing/src/points.rkt:158:2 make-exacts* [15] 59.3% loop [14] 40.7% ---------------------------------------------------------------------- prepare-points [6] 100.0% [10] 2656(21.0%) 0(0.0%) for-loop ...rbie/alt-testing/src/points.rkt:255:26 ??? [16] 100.0% ---------------------------------------------------------------------- setup-alt-simplified [7] 100.0% [11] 458(3.6%) 0(0.0%) simplify-alt ...rbie/alt-testing/src/glue.rkt:76:0 ??? [26] 100.0% ---------------------------------------------------------------------- for-loop [8] 100.0% [12] 352(2.8%) 0(0.0%) run-iter! ...ie/alt-testing/src/mainloop.rkt:206:0 localize-error [17] 100.0% ---------------------------------------------------------------------- prepare-points [6] 100.0% [13] 314(2.5%) 0(0.0%) filter-points .../alt-testing/src/points.rkt:171:0 for-loop [18] 100.0% ---------------------------------------------------------------------- loop [4] 7.3% loop [9] 24.4% prepare-points [6] 68.3% [14] 4492(35.6%) 0(0.0%) loop ...es/herbie/alt-testing/src/points.rkt:141:4 loop [19] 100.0% ---------------------------------------------------------------------- loop [4] 9.4% loop [9] 36.6% prepare-points [6] 53.9% [15] 4362(34.5%) 0(0.0%) make-exacts* ...e/alt-testing/src/points.rkt:138:0 loop [19] 100.0% ---------------------------------------------------------------------- for-loop [10] 100.0% [16] 2656(21.0%) 0(0.0%) ??? ...ies/herbie/alt-testing/src/points.rkt:230:7 ??? [22] 50.2% for-loop [20] 49.8% ---------------------------------------------------------------------- run-iter! [12] 100.0% [17] 352(2.8%) 0(0.0%) localize-error ...sting/src/core/localize.rkt:47:0 hash-ref! [27] 100.0% ---------------------------------------------------------------------- filter-points [13] 100.0% [18] 314(2.5%) 314(2.5%) for-loop ...erbie/alt-testing/src/points.rkt:174:4 ---------------------------------------------------------------------- make-exacts* [15] 0.1% loop [14] 0.6% parse-loop12 [33] 3.8% loop [19] 95.4% [19] 9206(72.9%) 0(0.0%) loop ...cket/collects/racket/private/map.rkt:25:19 loop [19] 95.4% ??? [23] 3.8% ??? [21] 0.7% ??? [22] 0.0% ---------------------------------------------------------------------- ??? [16] 100.0% [20] 1324(10.5%) 0(0.0%) for-loop ...erbie/alt-testing/src/points.rkt:231:9 ??? [22] 100.0% ---------------------------------------------------------------------- loop [19] 100.0% [21] 8400(66.5%) 1188(9.4%) ??? ...s/herbie/alt-testing/src/programs.rkt:156:4 ??? [24] 64.7% ??? [25] 21.1% ---------------------------------------------------------------------- loop [19] 14.6% for-loop [20] 42.6% ??? [16] 42.8% [22] 3110(24.6%) 2656(21.0%) ??? ...tract/private/arrow-higher-order.rkt:342:33 ??? [26] 14.6% ---------------------------------------------------------------------- loop [19] 100.0% [23] 352(2.8%) 0(0.0%) ??? ...ket/collects/racket/private/list.rkt:323:18 hash-ref! [27] 100.0% ---------------------------------------------------------------------- ??? [21] 100.0% [24] 5436(43.0%) 2166(17.1%) ??? ...acket/collects/racket/private/kw.rkt:444:14 bf* [28] 40.7% ??? [32] 7.0% ??? [35] 6.4% ...higher-order.rkt:346:33 [34] 6.0% ---------------------------------------------------------------------- ??? [21] 100.0% [25] 1776(14.1%) 0(0.0%) ??? ...contract/private/../../private/kw.rkt:761:9 real->bigfloat [29] 100.0% ---------------------------------------------------------------------- ??? [26] 25.1% simplify-alt [11] 25.1% ??? [22] 49.8% [26] 912(7.2%) 0(0.0%) ??? ...llects/racket/private/norm-define.rkt:53:83 ??? [31] 49.8% ??? [26] 25.1% iterate-egraph!13 [30] 25.1% ---------------------------------------------------------------------- ??? [23] 50.0% localize-error [17] 50.0% [27] 352(2.8%) 0(0.0%) hash-ref! ...racket/private/more-scheme.rkt:373:13 parse-loop12 [33] 100.0% ---------------------------------------------------------------------- ??? [24] 100.0% [28] 2212(17.5%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [35] 100.0% ---------------------------------------------------------------------- ??? [25] 100.0% [29] 1776(14.1%) 1776(14.1%) real->bigfloat .../private/bigfloat/mpfr.rkt:451:0 ---------------------------------------------------------------------- ??? [26] 100.0% [30] 458(3.6%) 0(0.0%) iterate-egraph!13 ...g/src/core/simplify.rkt:105:0 one-iter [36] 100.0% ---------------------------------------------------------------------- ??? [26] 100.0% [31] 454(3.6%) 0(0.0%) ??? ...-racket/utils/simple-result-arrow.rkt:60:15 ??? [41] 100.0% ---------------------------------------------------------------------- ??? [24] 100.0% [32] 382(3.0%) 382(3.0%) ??? ...rivate/unconstrained-domain-arrow.rkt:40:13 ---------------------------------------------------------------------- hash-ref! [27] 100.0% [33] 352(2.8%) 0(0.0%) parse-loop12 ...s/racket/match/compiler.rkt:418:15 loop [19] 50.0% compose [37] 50.0% ---------------------------------------------------------------------- ??? [24] 100.0% [34] 328(2.6%) 0(0.0%) ...higher-order.rkt:346:33 (unknown source) ??? [38] 100.0% ---------------------------------------------------------------------- ??? [24] 13.6% bf* [28] 86.4% [35] 2560(20.3%) 1056(8.4%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [39] 58.8% ---------------------------------------------------------------------- iterate-egraph!13 [30] 100.0% [36] 458(3.6%) 0(0.0%) one-iter ...lt-testing/src/core/simplify.rkt:116:0 for-loop [40] 100.0% ---------------------------------------------------------------------- parse-loop12 [33] 100.0% [37] 352(2.8%) 352(2.8%) compose ...collects/racket/private/list.rkt:385:12 ---------------------------------------------------------------------- ...higher-order.rkt:346:33 [34]100.0% [38] 328(2.6%) 328(2.6%) ??? ...cts/racket/contract/private/list.rkt:187:10 ---------------------------------------------------------------------- ??? [35] 100.0% [39] 1504(11.9%) 1198(9.5%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:819:4 ??? [41] 20.3% ---------------------------------------------------------------------- one-iter [36] 100.0% [40] 458(3.6%) 0(0.0%) for-loop ...lt-testing/src/core/simplify.rkt:166:2 for-loop [42] 100.0% ---------------------------------------------------------------------- bfmul [39] 40.3% ??? [31] 59.7% [41] 760(6.0%) 760(6.0%) ??? .../share/racket/collects/ffi/unsafe.rkt:861:4 ---------------------------------------------------------------------- for-loop [40] 100.0% [42] 458(3.6%) 0(0.0%) for-loop ...t-testing/src/core/simplify.rkt:148:10 merge-egraph-nodes! [43] 100.0% ---------------------------------------------------------------------- for-loop [42] 100.0% [43] 458(3.6%) 0(0.0%) merge-egraph-nodes! ...g/src/core/egraph.rkt:153:0 enode-merge! [44] 100.0% ---------------------------------------------------------------------- merge-egraph-nodes! [43] 100.0% [44] 458(3.6%) 0(0.0%) enode-merge! ...t-testing/src/core/enode.rkt:129:0 custom-set-intersect! [45] 100.0% ---------------------------------------------------------------------- enode-merge! [44] 100.0% [45] 458(3.6%) 0(0.0%) custom-set-intersect! ...ivate/set-types.rkt:269:0 for-loop [46] 100.0% ---------------------------------------------------------------------- custom-set-intersect! [45] 100.0% [46] 458(3.6%) 458(3.6%) for-loop ...cts/racket/private/set-types.rkt:282:4 ----------------------------------------------------------------------