Profiling results ----------------- Total cpu time observed: 21814ms (out of 22040ms) Number of samples taken: 99 (once every 220ms) (Hiding functions with self<1.0% and local<2.0%: 1 of 67 hidden) =============================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee =============================================================================== [1] 21814(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [3] 100.0% ------------------------------------------------------------------------------- hash-ref! [23] 100.0% [2] 3656(16.8%) 0(0.0%) ??? ...y-regraph-rebuilding/src/programs.rkt:178:5 loop [18] 100.0% ------------------------------------------------------------------------------- ??? [1] 100.0% [3] 21814(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [4] 100.0% ------------------------------------------------------------------------------- profile-thunk16 [3] 100.0% [4] 21814(100.0%) 0(0.0%) run ...et-7.5/share/pkgs/profile-lib/main.rkt:39:2 .../more-scheme.rkt:261:28 [5] 100.0% ------------------------------------------------------------------------------- for-loop [12] 0.5% run [4] 99.5% [5] 21814(100.0%) 0(0.0%) .../more-scheme.rkt:261:28 (unknown source) loop [9] 64.1% run-improve47 [6] 34.0% oracle-error [7] 1.0% errors [8] 0.5% ------------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [5] 100.0% [6] 7412(34.0%) 0(0.0%) run-improve47 ...rebuilding/src/mainloop.rkt:346:0 for-loop [10] 51.5% simplify! [17] 24.9% loop [9] 23.6% ------------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [5] 100.0% [7] 222(1.0%) 0(0.0%) oracle-error ...ph-rebuilding/src/points.rkt:177:0 for-loop [11] 100.0% ------------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [5] 100.0% [8] 200(0.9%) 0(0.0%) errors ...-regraph-rebuilding/src/points.rkt:190:0 for-loop [12] 100.0% ------------------------------------------------------------------------------- run-improve47 [6] 11.1% .../more-scheme.rkt:261:28 [5] 88.9% [9] 15730(72.1%) 0(0.0%) loop ...fy-regraph-rebuilding/src/points.rkt:266:2 loop [13] 48.1% make-exacts-walkup [15] 26.5% loop [16] 25.3% ------------------------------------------------------------------------------- run-improve47 [6] 100.0% [10] 3818(17.5%) 0(0.0%) for-loop ...raph-rebuilding/src/mainloop.rkt:365:4 run-iter! [14] 100.0% ------------------------------------------------------------------------------- oracle-error [7] 100.0% [11] 222(1.0%) 222(1.0%) for-loop ...egraph-rebuilding/src/points.rkt:178:2 ------------------------------------------------------------------------------- errors [8] 100.0% [12] 200(0.9%) 0(0.0%) for-loop ...egraph-rebuilding/src/points.rkt:192:2 .../more-scheme.rkt:261:28 [5] 100.0% ------------------------------------------------------------------------------- loop [9] 100.0% [13] 7572(34.7%) 0(0.0%) loop ...fy-regraph-rebuilding/src/points.rkt:230:2 make-exacts-walkup [15] 68.5% loop [16] 31.5% ------------------------------------------------------------------------------- for-loop [10] 100.0% [14] 3818(17.5%) 0(0.0%) run-iter! ...aph-rebuilding/src/mainloop.rkt:327:0 simplify! [17] 100.0% ------------------------------------------------------------------------------- loop [9] 44.6% loop [13] 55.4% [15] 9364(42.9%) 0(0.0%) make-exacts-walkup ...uilding/src/points.rkt:209:0 loop [18] 61.0% eval-prog [20] 39.0% ------------------------------------------------------------------------------- loop [13] 37.4% loop [9] 62.6% [16] 6366(29.2%) 0(0.0%) loop ...fy-regraph-rebuilding/src/points.rkt:213:4 loop [18] 100.0% ------------------------------------------------------------------------------- run-improve47 [6] 32.6% run-iter! [14] 67.4% [17] 5662(26.0%) 0(0.0%) simplify! ...aph-rebuilding/src/mainloop.rkt:224:0 ??? [19] 100.0% ------------------------------------------------------------------------------- make-exacts-walkup [15] 0.2% loop [16] 1.3% ??? [2] 23.2% loop [18] 75.3% [18] 15730(72.1%) 242(1.1%) loop (unknown source) loop [18] 75.3% hash-ref! [23] 23.2% composed [21] 1.4% ------------------------------------------------------------------------------- simplify! [17] 100.0% [19] 5662(26.0%) 0(0.0%) ??? ...graph-rebuilding/src/core/simplify.rkt:38:0 ??? [22] 100.0% ------------------------------------------------------------------------------- make-exacts-walkup [15] 100.0% [20] 3656(16.8%) 0(0.0%) eval-prog ...raph-rebuilding/src/programs.rkt:82:0 hash-ref! [23] 100.0% ------------------------------------------------------------------------------- loop [18] 100.0% [21] 11832(54.2%) 444(2.0%) composed ...ollects/racket/private/list.rkt:321:16 ??? [24] 82.6% ??? [38] 11.6% ??? [27] 2.0% ------------------------------------------------------------------------------- ??? [19] 100.0% [22] 5662(26.0%) 0(0.0%) ??? ...graph-rebuilding/src/core/simplify.rkt:56:0 for-loop [25] 100.0% ------------------------------------------------------------------------------- eval-prog [20] 20.4% loop [18] 79.6% [23] 3656(16.8%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 ??? [2] 79.6% bigfloat-hash [26] 20.4% ------------------------------------------------------------------------------- composed [21] 100.0% [24] 9772(44.8%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:379:33 bf-complex-mult [28] 84.0% bf-complex-add [31] 16.0% ------------------------------------------------------------------------------- ??? [22] 100.0% [25] 5662(26.0%) 0(0.0%) for-loop ...-rebuilding/src/core/simplify.rkt:76:2 for-loop [29] 100.0% ------------------------------------------------------------------------------- hash-ref! [23] 100.0% [26] 3656(16.8%) 0(0.0%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:168:0 bfcanonicalize [30] 100.0% ------------------------------------------------------------------------------- composed [21] 100.0% [27] 240(1.1%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:375:33 ??? [32] 100.0% ------------------------------------------------------------------------------- ??? [24] 100.0% [28] 8206(37.6%) 446(2.0%) bf-complex-mult ...uilding/src/bigcomplex.rkt:30:0 bf* [34] 52.2% ??? [38] 39.2% ...higher-order.rkt:379:33 [36] 3.1% ------------------------------------------------------------------------------- for-loop [25] 100.0% [29] 5662(26.0%) 0(0.0%) for-loop ...-rebuilding/src/core/simplify.rkt:80:4 ??? [33] 81.1% loop [35] 15.3% egraph-rebuild [37] 3.6% ------------------------------------------------------------------------------- bigfloat-hash [26] 100.0% [30] 3656(16.8%) 3656(16.8%) bfcanonicalize .../private/bigfloat/mpfr.rkt:158:0 ------------------------------------------------------------------------------- ??? [24] 100.0% [31] 1566(7.2%) 248(1.1%) bf-complex-add ...building/src/bigcomplex.rkt:23:0 ??? [38] 69.9% ...higher-order.rkt:379:33 [36] 14.3% ------------------------------------------------------------------------------- ??? [27] 100.0% [32] 240(1.1%) 240(1.1%) ??? ...ects/racket/contract/private/guts.rkt:770:8 ------------------------------------------------------------------------------- for-loop [29] 100.0% [33] 4590(21.0%) 0(0.0%) ??? ...fy-regraph-rebuilding/regraph/main.rkt:45:0 find-matches [39] 82.5% for-loop [41] 17.5% ------------------------------------------------------------------------------- bf-complex-mult [28] 100.0% [34] 4284(19.6%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [38] 100.0% ------------------------------------------------------------------------------- for-loop [29] 100.0% [35] 868(4.0%) 0(0.0%) loop ...aph-rebuilding/regraph/extraction.rkt:25:2 for-loop [40] 100.0% ------------------------------------------------------------------------------- bf-complex-add [31] 46.5% bf-complex-mult [28] 53.5% [36] 482(2.2%) 0(0.0%) ...higher-order.rkt:379:33 (unknown source) ??? [42] 100.0% ------------------------------------------------------------------------------- for-loop [29] 100.0% [37] 204(0.9%) 0(0.0%) egraph-rebuild ...building/regraph/egraph.rkt:61:0 egraph-rebuild-once [43] 100.0% ------------------------------------------------------------------------------- bf-complex-add [31] 11.0% composed [21] 13.8% bf-complex-mult [28] 32.3% bf* [34] 43.0% [38] 9972(45.7%) 2224(10.2%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [44] 65.6% bfneg [46] 12.1% ------------------------------------------------------------------------------- ??? [33] 100.0% [39] 3788(17.4%) 0(0.0%) find-matches ...h-rebuilding/regraph/main.rkt:37:0 for-loop [45] 100.0% ------------------------------------------------------------------------------- loop [35] 100.0% [40] 868(4.0%) 222(1.0%) for-loop ...rebuilding/regraph/extraction.rkt:28:4 for-loop [48] 74.4% ------------------------------------------------------------------------------- ??? [33] 100.0% [41] 802(3.7%) 0(0.0%) for-loop ...graph-rebuilding/regraph/main.rkt:48:2 for-loop [47] 100.0% ------------------------------------------------------------------------------- ...higher-order.rkt:379:33 [36] 100.0% [42] 482(2.2%) 482(2.2%) ??? ...cts/racket/contract/private/list.rkt:209:10 ------------------------------------------------------------------------------- egraph-rebuild [37] 100.0% [43] 204(0.9%) 0(0.0%) egraph-rebuild-once ...ing/regraph/egraph.rkt:68:0 for-loop [49] 100.0% ------------------------------------------------------------------------------- ??? [38] 100.0% [44] 6546(30.0%) 692(3.2%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:835:4 new-mpfr [50] 55.3% ??? [52] 34.1% ------------------------------------------------------------------------------- for-loop [45] 50.0% find-matches [39] 50.0% [45] 3788(17.4%) 474(2.2%) for-loop ...graph-rebuilding/regraph/main.rkt:39:2 for-loop [45] 50.0% match-e [51] 43.7% ------------------------------------------------------------------------------- ??? [38] 100.0% [46] 1202(5.5%) 0(0.0%) bfneg ...-lib/math/private/bigfloat/mpfr.rkt:632:4 new-mpfr [50] 80.0% ??? [52] 20.0% ------------------------------------------------------------------------------- for-loop [41] 100.0% [47] 802(3.7%) 0(0.0%) for-loop ...graph-rebuilding/regraph/main.rkt:51:4 add-enode-rec! [53] 100.0% ------------------------------------------------------------------------------- for-loop [40] 100.0% [48] 646(3.0%) 224(1.0%) for-loop ...ebuilding/regraph/extraction.rkt:36:16 for-loop [54] 65.3% ------------------------------------------------------------------------------- egraph-rebuild-once [43] 100.0% [49] 204(0.9%) 0(0.0%) for-loop ...aph-rebuilding/regraph/egraph.rkt:72:2 for-loop [55] 100.0% ------------------------------------------------------------------------------- bfneg [46] 21.0% bfmul [44] 79.0% [50] 4582(21.0%) 4582(21.0%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:348:0 ------------------------------------------------------------------------------- for-loop [59] 15.8% for-loop [45] 84.2% [51] 3314(15.2%) 1026(4.7%) match-e ...raph-rebuilding/regraph/ematch.rkt:35:0 for-loop [56] 72.4% ------------------------------------------------------------------------------- bfneg [46] 9.7% bfmul [44] 90.3% [52] 2474(11.3%) 2474(11.3%) ??? /opt/racket-7.5/collects/ffi/unsafe.rkt:938:4 ------------------------------------------------------------------------------- for-loop [47] 100.0% [53] 802(3.7%) 200(0.9%) add-enode-rec! ...uilding/regraph/egraph.rkt:161:0 map [57] 50.1% mk-enode! [58] 24.9% ------------------------------------------------------------------------------- for-loop [48] 100.0% [54] 422(1.9%) 422(1.9%) for-loop ...ebuilding/regraph/extraction.rkt:40:23 ------------------------------------------------------------------------------- for-loop [49] 100.0% [55] 204(0.9%) 204(0.9%) for-loop ...aph-rebuilding/regraph/egraph.rkt:73:4 ------------------------------------------------------------------------------- match-e [51] 100.0% [56] 2510(11.5%) 1642(7.5%) for-loop ...ph-rebuilding/regraph/ematch.rkt:41:11 for-loop [59] 25.3% do-curry [62] 8.8% procedure-reduce-keyword-arity-mask [63] 7.9% make-curried [61] 4.5% ------------------------------------------------------------------------------- add-enode-rec! [53] 100.0% [57] 402(1.8%) 0(0.0%) map ...t-7.5/collects/racket/private/map.rkt:35:13 mk-enode-rec! [60] 100.0% ------------------------------------------------------------------------------- add-enode-rec! [53] 100.0% [58] 200(0.9%) 0(0.0%) mk-enode! ...h-rebuilding/regraph/egraph.rkt:132:0 new-enode [64] 100.0% ------------------------------------------------------------------------------- for-loop [56] 100.0% [59] 1050(4.8%) 0(0.0%) for-loop ...ph-rebuilding/regraph/ematch.rkt:47:37 match-e [51] 100.0% ------------------------------------------------------------------------------- map [57] 100.0% [60] 402(1.8%) 0(0.0%) mk-enode-rec! ...building/regraph/egraph.rkt:152:0 hash-has-key? [65] 50.2% new-enode [64] 49.8% ------------------------------------------------------------------------------- for-loop [56] 100.0% [61] 226(1.0%) 226(1.0%) make-curried ...collects/racket/function.rkt:130:9 ------------------------------------------------------------------------------- for-loop [56] 100.0% [62] 222(1.0%) 222(1.0%) do-curry ...-7.5/collects/racket/function.rkt:71:2 ------------------------------------------------------------------------------- for-loop [56] 100.0% [63] 198(0.9%) 198(0.9%) procedure-reduce-keyword-arity-mask ....rkt:1597:4 ------------------------------------------------------------------------------- mk-enode-rec! [60] 50.0% mk-enode! [58] 50.0% [64] 400(1.8%) 0(0.0%) new-enode ...aph-rebuilding/regraph/enode.rkt:64:0 set [66] 100.0% ------------------------------------------------------------------------------- mk-enode-rec! [60] 100.0% [65] 202(0.9%) 202(0.9%) hash-has-key? ...ket/private/more-scheme.rkt:371:2 ------------------------------------------------------------------------------- new-enode [64] 100.0% [66] 400(1.8%) 400(1.8%) set ...collects/racket/private/set-types.rkt:992:0 -------------------------------------------------------------------------------