Profiling results ----------------- Total cpu time observed: 21210ms (out of 21904ms) Number of samples taken: 53 (once every 400ms) ====================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ====================================================================== run [8] 50.0% [1] 21210(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [3] 50.0% prepare-points [4] 24.0% loop [5] 20.5% run-improve48 [6] 4.8% get-final-combination [7] 0.7% ---------------------------------------------------------------------- ??? [15] 10.3% loop [32] 89.7% [2] 4096(19.3%) 330(1.6%) ??? ...tract/private/arrow-higher-order.rkt:342:33 ??? [13] 91.9% ---------------------------------------------------------------------- ??? [1] 100.0% [3] 21210(100.0%) 0(0.0%) profile-thunk16 ...t/pkgs/profile-lib/main.rkt:9:0 run [8] 100.0% ---------------------------------------------------------------------- ??? [1] 100.0% [4] 10182(48.0%) 0(0.0%) prepare-points ...an-out-code/src/points.rkt:156:0 loop [12] 48.2% make-exacts* [14] 27.4% loop [9] 24.4% ---------------------------------------------------------------------- ??? [1] 100.0% [5] 8678(40.9%) 0(0.0%) loop ...herbie/clean-out-code/src/points.rkt:180:2 loop [12] 54.1% loop [9] 24.7% make-exacts* [14] 16.4% for-loop [11] 4.9% ---------------------------------------------------------------------- ??? [1] 100.0% [6] 2040(9.6%) 0(0.0%) run-improve48 ...n-out-code/src/mainloop.rkt:286:0 for-loop [10] 100.0% ---------------------------------------------------------------------- ??? [1] 100.0% [7] 310(1.5%) 0(0.0%) get-final-combination ...de/src/mainloop.rkt:320:0 ??? [13] 100.0% ---------------------------------------------------------------------- profile-thunk16 [3] 100.0% [8] 21210(100.0%) 0(0.0%) run ...share/racket/pkgs/profile-lib/main.rkt:39:2 ??? [1] 100.0% ---------------------------------------------------------------------- loop [5] 46.3% prepare-points [4] 53.7% [9] 4626(21.8%) 0(0.0%) loop ...herbie/clean-out-code/src/points.rkt:124:2 loop [12] 59.6% make-exacts* [14] 40.4% ---------------------------------------------------------------------- run-improve48 [6] 100.0% [10] 2040(9.6%) 0(0.0%) for-loop ...clean-out-code/src/mainloop.rkt:298:29 ??? [13] 100.0% ---------------------------------------------------------------------- loop [5] 100.0% [11] 422(2.0%) 0(0.0%) for-loop ...e/clean-out-code/src/points.rkt:195:19 ??? [15] 100.0% ---------------------------------------------------------------------- loop [9] 22.3% loop [5] 38.0% prepare-points [4] 39.7% [12] 12354(58.2%) 0(0.0%) loop ...herbie/clean-out-code/src/points.rkt:107:4 loop [32] 100.0% ---------------------------------------------------------------------- get-final-combination [7] 5.1% get-sigs [48] 8.2% for-loop [10] 25.2% ??? [2] 61.6% [13] 6116(28.8%) 1360(6.4%) ??? ...llects/racket/private/norm-define.rkt:53:83 iterate-egraph!7 [16] 30.2% return/no-unsupplied [17] 27.1% loop [19] 6.9% ??? [20] 5.6% ??? [18] 5.6% ??? [21] 4.9% ---------------------------------------------------------------------- loop [5] 23.4% loop [9] 30.7% prepare-points [4] 45.9% [14] 6084(28.7%) 0(0.0%) make-exacts* ...lean-out-code/src/points.rkt:104:0 loop [32] 100.0% ---------------------------------------------------------------------- for-loop [11] 100.0% [15] 422(2.0%) 0(0.0%) ??? .../herbie/clean-out-code/src/points.rkt:172:7 ??? [2] 100.0% ---------------------------------------------------------------------- ??? [13] 100.0% [16] 2350(11.1%) 0(0.0%) iterate-egraph!7 ...ode/src/core/simplify.rkt:55:0 one-iter [22] 86.9% map-enodes [26] 13.1% ---------------------------------------------------------------------- ??? [13] 100.0% [17] 1658(7.8%) 0(0.0%) return/no-unsupplied ...ct/private/arr-i.rkt:533:0 filter [23] 100.0% ---------------------------------------------------------------------- ??? [13] 100.0% [18] 686(3.2%) 312(1.5%) ??? ...ects/racket/contract/private/list.rkt:737:4 ??? [25] 54.5% ---------------------------------------------------------------------- ??? [13] 100.0% [19] 422(2.0%) 0(0.0%) loop .../herbie/clean-out-code/src/points.rkt:65:6 random-exp [24] 100.0% ---------------------------------------------------------------------- ??? [13] 100.0% [20] 344(1.6%) 0(0.0%) ??? ...-racket/utils/simple-result-arrow.rkt:60:15 ??? [55] 100.0% ---------------------------------------------------------------------- ??? [13] 100.0% [21] 298(1.4%) 0(0.0%) ??? ...racket/collects/racket/private/kw.rkt:761:9 ??? [54] 100.0% ---------------------------------------------------------------------- iterate-egraph!7 [16] 100.0% [22] 2042(9.6%) 0(0.0%) one-iter ...an-out-code/src/core/simplify.rkt:66:0 for-loop [27] 79.4% for-loop [28] 20.6% ---------------------------------------------------------------------- return/no-unsupplied [17] 100.0% [23] 1658(7.8%) 1658(7.8%) filter ...t/collects/racket/private/list.rkt:256:2 ---------------------------------------------------------------------- loop [19] 100.0% [24] 422(2.0%) 422(2.0%) random-exp .../clean-out-code/src/common.rkt:243:0 ---------------------------------------------------------------------- ??? [18] 100.0% [25] 374(1.8%) 0(0.0%) ??? ...private/unconstrained-domain-arrow.rkt:74:4 make-keyword-procedure [29] 100.0% ---------------------------------------------------------------------- iterate-egraph!7 [16] 100.0% [26] 308(1.5%) 0(0.0%) map-enodes ...n-out-code/src/core/egraph.rkt:140:0 loop [32] 100.0% ---------------------------------------------------------------------- one-iter [22] 100.0% [27] 1622(7.6%) 0(0.0%) for-loop ...n-out-code/src/core/simplify.rkt:116:2 for-loop [30] 80.9% match-e [31] 19.1% ---------------------------------------------------------------------- for-loop [28] 50.0% one-iter [22] 50.0% [28] 420(2.0%) 0(0.0%) for-loop ...n-out-code/src/core/simplify.rkt:76:12 for-loop [28] 50.0% match-e [31] 50.0% ---------------------------------------------------------------------- ??? [25] 100.0% [29] 374(1.8%) 374(1.8%) make-keyword-procedure ...ket/private/kw.rkt:260:4 ---------------------------------------------------------------------- for-loop [27] 100.0% [30] 1312(6.2%) 0(0.0%) for-loop ...n-out-code/src/core/simplify.rkt:98:10 mk-enode! [33] 76.4% merge-egraph-nodes! [34] 23.6% ---------------------------------------------------------------------- for-loop [27] 42.5% for-loop [28] 57.5% [31] 730(3.4%) 420(2.0%) match-e ...clean-out-code/src/core/ematch.rkt:46:0 loop [32] 42.5% ---------------------------------------------------------------------- map-enodes [26] 0.0% loop! [44] 0.4% loop [12] 0.7% match-e [31] 1.6% make-exacts* [14] 2.0% loop [32] 95.2% [32] 19366(91.3%) 1026(4.8%) loop ...cket/collects/racket/private/map.rkt:25:19 loop [32] 95.2% ??? [35] 1.7% loop [37] 1.6% ??? [2] 1.1% loop! [44] 0.4% for-loop [39] 0.0% ---------------------------------------------------------------------- for-loop [30] 100.0% [33] 1002(4.7%) 0(0.0%) mk-enode! ...an-out-code/src/core/egraph.rkt:102:0 new-enode [36] 100.0% ---------------------------------------------------------------------- for-loop [30] 100.0% [34] 310(1.5%) 0(0.0%) merge-egraph-nodes! ...e/src/core/egraph.rkt:153:0 for-loop [38] 100.0% ---------------------------------------------------------------------- loop [32] 100.0% [35] 13738(64.8%) 776(3.7%) ??? ...erbie/clean-out-code/src/programs.rkt:129:2 ??? [40] 47.1% ??? [41] 44.6% ??? [43] 2.6% ---------------------------------------------------------------------- mk-enode! [33] 100.0% [36] 1002(4.7%) 0(0.0%) new-enode ...lean-out-code/src/core/enode.rkt:97:0 type-of-enode-expr [42] 100.0% ---------------------------------------------------------------------- loop [32] 100.0% [37] 310(1.5%) 310(1.5%) loop ...e/racket/collects/racket/function.rkt:53:6 ---------------------------------------------------------------------- merge-egraph-nodes! [34] 100.0% [38] 310(1.5%) 0(0.0%) for-loop ...an-out-code/src/core/egraph.rkt:201:10 loop! [44] 100.0% ---------------------------------------------------------------------- loop [32] 100.0% [39] 308(1.5%) 0(0.0%) for-loop ...n-out-code/src/core/simplify.rkt:145:2 setfindf [45] 100.0% ---------------------------------------------------------------------- ??? [35] 100.0% [40] 6476(30.5%) 340(1.6%) ??? ...contract/private/../../private/kw.rkt:761:9 real->bigfloat [46] 94.7% ---------------------------------------------------------------------- ??? [35] 100.0% [41] 6128(28.9%) 1334(6.3%) ??? ...acket/collects/racket/private/kw.rkt:444:14 bf* [47] 50.8% ??? [51] 21.4% ...higher-order.rkt:346:33 [49] 6.1% ---------------------------------------------------------------------- new-enode [36] 100.0% [42] 1002(4.7%) 0(0.0%) type-of-enode-expr ...code/src/core/enode.rkt:68:0 get-sigs [48] 100.0% ---------------------------------------------------------------------- ??? [35] 100.0% [43] 358(1.7%) 358(1.7%) ??? ...tract/private/arrow-higher-order.rkt:145:21 ---------------------------------------------------------------------- loop [32] 50.0% for-loop [38] 50.0% [44] 310(1.5%) 310(1.5%) loop! ...e/clean-out-code/src/core/enode.rkt:192:2 loop [32] 50.0% ---------------------------------------------------------------------- for-loop [39] 100.0% [45] 308(1.5%) 0(0.0%) setfindf ...ie/clean-out-code/src/common.rkt:176:0 for-loop [50] 100.0% ---------------------------------------------------------------------- ??? [40] 100.0% [46] 6136(28.9%) 4730(22.3%) real->bigfloat .../private/bigfloat/mpfr.rkt:451:0 ??? [55] 22.9% ---------------------------------------------------------------------- ??? [41] 100.0% [47] 3110(14.7%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [51] 100.0% ---------------------------------------------------------------------- type-of-enode-expr [42] 100.0% [48] 1002(4.7%) 0(0.0%) get-sigs .../clean-out-code/src/type-check.rkt:5:0 ??? [13] 100.0% ---------------------------------------------------------------------- ??? [41] 100.0% [49] 372(1.8%) 0(0.0%) ...higher-order.rkt:346:33 (unknown source) ??? [52] 100.0% ---------------------------------------------------------------------- setfindf [45] 100.0% [50] 308(1.5%) 308(1.5%) for-loop ...ie/clean-out-code/src/common.rkt:177:2 ---------------------------------------------------------------------- ??? [41] 29.7% bf* [47] 70.3% [51] 4422(20.8%) 1046(4.9%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [53] 76.3% ---------------------------------------------------------------------- ...higher-order.rkt:346:33 [49]100.0% [52] 372(1.8%) 0(0.0%) ??? ...cts/racket/contract/private/list.rkt:187:10 ??? [54] 100.0% ---------------------------------------------------------------------- ??? [51] 100.0% [53] 3376(15.9%) 3030(14.3%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:819:4 ??? [55] 10.2% ---------------------------------------------------------------------- ??? [21] 44.5% ??? [52] 55.5% [54] 670(3.2%) 670(3.2%) ??? ...ects/racket/contract/private/guts.rkt:644:8 ---------------------------------------------------------------------- ??? [20] 16.4% bfmul [53] 16.5% real->bigfloat [46] 67.1% [55] 2096(9.9%) 2096(9.9%) ??? .../share/racket/collects/ffi/unsafe.rkt:861:4 ----------------------------------------------------------------------