Profiling results ----------------- Total cpu time observed: 6596ms (out of 6988ms) Number of samples taken: 22 (once every 300ms) ============================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ============================================================== [1] 6596(100.0%) 0(0.0%) ??? ...are/racket/collects/racket/engine.rkt:42:24 profile-thunk [3] 100.0% -------------------------------------------------------------- loop [26] 100.0% [2] 276(4.2%) 0(0.0%) composed ...ollects/racket/private/list.rkt:341:16 hash-ref! [21] 100.0% -------------------------------------------------------------- ??? [1] 100.0% [3] 6596(100.0%) 0(0.0%) profile-thunk ...ket/pkgs/profile-lib/main.rkt:9:0 ??? [4] 100.0% -------------------------------------------------------------- profile-thunk [3] 100.0% [4] 6596(100.0%) 0(0.0%) ??? ...hare/racket/pkgs/profile-lib/main.rkt:40:10 ??? [5] 100.0% -------------------------------------------------------------- ??? [4] 100.0% [5] 6596(100.0%) 0(0.0%) ??? ...ies/herbie/alt-testing/src/sandbox.rkt:49:6 prepare-points [6] 74.8% run-improve [7] 25.2% -------------------------------------------------------------- ??? [5] 100.0% [6] 4936(74.8%) 0(0.0%) prepare-points ...alt-testing/src/points.rkt:214:0 loop [11] 71.8% make-exacts [8] 28.2% -------------------------------------------------------------- ??? [5] 100.0% [7] 1660(25.2%) 0(0.0%) run-improve .../alt-testing/src/mainloop.rkt:225:0 setup-alt-simplified [9] 83.4% run-iter! [10] 16.6% -------------------------------------------------------------- prepare-points [6] 100.0% [8] 1393(21.1%) 0(0.0%) make-exacts ...ie/alt-testing/src/points.rkt:156:0 loop [11] 80.2% make-exacts* [14] 19.8% -------------------------------------------------------------- run-improve [7] 100.0% [9] 1384(21.0%) 0(0.0%) setup-alt-simplified ...-testing/src/glue.rkt:49:0 simplify-alt [12] 100.0% -------------------------------------------------------------- run-improve [7] 100.0% [10] 276(4.2%) 0(0.0%) run-iter! ...ie/alt-testing/src/mainloop.rkt:206:0 localize! [13] 100.0% -------------------------------------------------------------- make-exacts [8] 24.0% prepare-points [6] 76.0% [11] 4660(70.7%) 0(0.0%) loop ...es/herbie/alt-testing/src/points.rkt:141:4 loop [26] 100.0% -------------------------------------------------------------- setup-alt-simplified [9]100.0% [12] 1384(21.0%) 0(0.0%) simplify-alt ...rbie/alt-testing/src/glue.rkt:76:0 simplify [15] 100.0% -------------------------------------------------------------- run-iter! [10] 100.0% [13] 276(4.2%) 0(0.0%) localize! ...ie/alt-testing/src/mainloop.rkt:115:0 localize-error [16] 100.0% -------------------------------------------------------------- make-exacts [8] 100.0% [14] 276(4.2%) 0(0.0%) make-exacts* ...e/alt-testing/src/points.rkt:138:0 eval-prog [17] 100.0% -------------------------------------------------------------- simplify-alt [12] 100.0% [15] 1384(21.0%) 0(0.0%) simplify ...alt-testing/src/core/simplify.rkt:43:0 ??? [18] 100.0% -------------------------------------------------------------- localize! [13] 100.0% [16] 276(4.2%) 0(0.0%) localize-error ...sting/src/core/localize.rkt:47:0 hash-ref! [21] 100.0% -------------------------------------------------------------- make-exacts* [14] 100.0% [17] 276(4.2%) 0(0.0%) eval-prog ...ie/alt-testing/src/programs.rkt:150:0 compile [19] 100.0% -------------------------------------------------------------- simplify [15] 100.0% [18] 1384(21.0%) 0(0.0%) ??? ...rbie/alt-testing/src/core/simplify.rkt:77:0 iterate-egraph! [20] 100.0% -------------------------------------------------------------- eval-prog [17] 100.0% [19] 276(4.2%) 0(0.0%) compile ...rbie/alt-testing/src/programs.rkt:186:0 hash-ref! [21] 100.0% -------------------------------------------------------------- ??? [18] 100.0% [20] 1384(21.0%) 0(0.0%) iterate-egraph! ...ing/src/core/simplify.rkt:105:0 one-iter [22] 100.0% -------------------------------------------------------------- compile [19] 12.5% localize-error [16] 16.7% composed [2] 33.4% loop [26] 37.5% [21] 552(8.4%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:377:2 f8 [23] 50.0% ??? [25] 37.5% bigfloat-hash [24] 12.5% -------------------------------------------------------------- iterate-egraph! [20] 100.0% [22] 1384(21.0%) 279(4.2%) one-iter ...lt-testing/src/core/simplify.rkt:116:0 custom-set-member? [27] 20.2% merge-egraph-nodes! [28] 20.1% substitute-e [30] 19.9% set-member? [31] 19.8% -------------------------------------------------------------- hash-ref! [21] 100.0% [23] 276(4.2%) 0(0.0%) f8 ...et/collects/racket/match/compiler.rkt:548:40 loop [26] 100.0% -------------------------------------------------------------- hash-ref! [21] 100.0% [24] 276(4.2%) 0(0.0%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:168:0 bigfloat->sig+exp [29] 100.0% -------------------------------------------------------------- hash-ref! [21] 100.0% [25] 276(4.2%) 0(0.0%) ??? ...s/herbie/alt-testing/src/programs.rkt:194:5 loop [26] 100.0% -------------------------------------------------------------- loop [11] 0.1% f8 [23] 0.1% loop! [33] 0.8% ??? [25] 3.0% loop [26] 96.0% [26] 5490(83.2%) 0(0.0%) loop .../private/map.rkt:40:19 loop [26] 96.0% hash-ref! [21] 3.0% loop! [33] 0.8% composed [2] 0.1% ??? [32] 0.1% bf* [35] 0.0% -------------------------------------------------------------- one-iter [22] 100.0% [27] 279(4.2%) 279(4.2%) custom-set-member? ...t/private/set-types.rkt:45:0 -------------------------------------------------------------- one-iter [22] 100.0% [28] 278(4.2%) 0(0.0%) merge-egraph-nodes! ...g/src/core/egraph.rkt:153:0 loop! [33] 100.0% -------------------------------------------------------------- bigfloat-hash [24] 100.0% [29] 276(4.2%) 276(4.2%) bigfloat->sig+exp ...ivate/bigfloat/mpfr.rkt:389:0 -------------------------------------------------------------- substitute-e [30] 50.0% one-iter [22] 50.0% [30] 275(4.2%) 0(0.0%) substitute-e ...t-testing/src/core/ematch.rkt:71:0 substitute-e [30] 50.0% ??? [34] 50.0% -------------------------------------------------------------- one-iter [22] 100.0% [31] 274(4.1%) 274(4.1%) set-member? ...ts/racket/private/generic.rkt:472:9 -------------------------------------------------------------- loop [26] 100.0% [32] 4660(70.7%) 2414(36.6%) ??? ...s/herbie/alt-testing/src/programs.rkt:156:4 ??? [36] 21.1% bf* [35] 19.9% ??? [39] 7.2% -------------------------------------------------------------- loop [26] 50.0% merge-egraph-nodes! [28] 50.0% [33] 278(4.2%) 0(0.0%) loop! ...rbie/alt-testing/src/core/enode.rkt:192:2 loop [26] 50.0% make-immutable-set [37] 50.0% -------------------------------------------------------------- substitute-e [30] 100.0% [34] 275(4.2%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:486:18 hash-has-key? [38] 100.0% -------------------------------------------------------------- loop [26] 23.0% ??? [32] 77.0% [35] 1202(18.2%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [39] 100.0% -------------------------------------------------------------- ??? [32] 100.0% [36] 984(14.9%) 0(0.0%) ??? .../private/arrow-higher-order.rkt:379:33 ??? [40] 100.0% -------------------------------------------------------------- loop! [33] 100.0% [37] 278(4.2%) 278(4.2%) make-immutable-set .../private/set-types.rkt:948:0 -------------------------------------------------------------- ??? [34] 100.0% [38] 275(4.2%) 275(4.2%) hash-has-key? ...ket/private/more-scheme.rkt:372:2 -------------------------------------------------------------- ??? [32] 21.9% bf* [35] 78.1% [39] 1538(23.3%) 0(0.0%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [41] 100.0% -------------------------------------------------------------- ??? [36] 100.0% [40] 984(14.9%) 642(9.7%) ??? ...cts/racket/contract/private/list.rkt:215:14 ??? [42] 34.8% -------------------------------------------------------------- ??? [39] 100.0% [41] 1538(23.3%) 1170(17.7%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:846:4 new-mpfr [43] 24.0% -------------------------------------------------------------- ??? [40] 100.0% [42] 343(5.2%) 343(5.2%) ??? ...ects/racket/contract/private/guts.rkt:794:8 -------------------------------------------------------------- bfmul [41] 100.0% [43] 369(5.6%) 369(5.6%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:344:0 --------------------------------------------------------------