Profiling results ----------------- Total cpu time observed: 4358ms (out of 4600ms) Number of samples taken: 23 (once every 189ms) ============================================================================= Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ============================================================================= [1] 4358(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [2] 100.0% ----------------------------------------------------------------------------- ??? [1] 100.0% [2] 4358(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [3] 100.0% ----------------------------------------------------------------------------- profile-thunk16 [2] 100.0% [3] 4358(100.0%) 0(0.0%) run ...et-7.5/share/pkgs/profile-lib/main.rkt:39:2 .../more-scheme.rkt:261:28 [4] 100.0% ----------------------------------------------------------------------------- for-loop [12] 6.7% run [3] 93.3% [4] 4358(100.0%) 0(0.0%) .../more-scheme.rkt:261:28 (unknown source) prepare-points-intervals [5] 48.3% run-improve47 [6] 33.8% errors [7] 6.7% oracle-error [9] 4.5% ??? [8] 4.5% ??? [21] 2.2% ----------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [4] 100.0% [5] 2106(48.3%) 0(0.0%) prepare-points-intervals ...g/src/points.rkt:120:0 loop [10] 100.0% ----------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [4] 100.0% [6] 1474(33.8%) 0(0.0%) run-improve47 ...rebuilding/src/mainloop.rkt:346:0 simplify! [11] 100.0% ----------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [4] 100.0% [7] 582(13.4%) 0(0.0%) errors ...-regraph-rebuilding/src/points.rkt:190:0 for-loop [12] 100.0% ----------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [4] 100.0% [8] 388(8.9%) 388(8.9%) ??? ...aph-rebuilding/src/syntax/syntax.rkt:214:17 ----------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [4] 100.0% [9] 196(4.5%) 0(0.0%) oracle-error ...ph-rebuilding/src/points.rkt:177:0 for-loop [13] 100.0% ----------------------------------------------------------------------------- prepare-points-intervals [5] 100.0% [10] 2106(48.3%) 0(0.0%) loop ...fy-regraph-rebuilding/src/points.rkt:132:4 loop [15] 63.4% ??? [16] 36.6% ----------------------------------------------------------------------------- run-improve47 [6] 100.0% [11] 1474(33.8%) 0(0.0%) simplify! ...aph-rebuilding/src/mainloop.rkt:224:0 ??? [14] 100.0% ----------------------------------------------------------------------------- errors [7] 100.0% [12] 582(13.4%) 0(0.0%) for-loop ...egraph-rebuilding/src/points.rkt:192:2 .../more-scheme.rkt:261:28 [4] 100.0% ----------------------------------------------------------------------------- oracle-error [9] 100.0% [13] 196(4.5%) 0(0.0%) for-loop ...egraph-rebuilding/src/points.rkt:178:2 loop [40] 100.0% ----------------------------------------------------------------------------- simplify! [11] 100.0% [14] 1474(33.8%) 0(0.0%) ??? ...graph-rebuilding/src/core/simplify.rkt:38:0 ??? [17] 100.0% ----------------------------------------------------------------------------- loop [10] 100.0% [15] 1336(30.7%) 0(0.0%) loop ...ify-regraph-rebuilding/src/points.rkt:79:2 real->precision [18] 56.4% ??? [19] 43.6% ----------------------------------------------------------------------------- loop [10] 100.0% [16] 770(17.7%) 0(0.0%) ??? ...ify-regraph-rebuilding/src/points.rkt:113:6 loop [40] 50.1% sample-multi-bounded [43] 49.9% ----------------------------------------------------------------------------- ??? [14] 100.0% [17] 1474(33.8%) 0(0.0%) ??? ...graph-rebuilding/src/core/simplify.rkt:56:0 for-loop [20] 100.0% ----------------------------------------------------------------------------- loop [15] 100.0% [18] 754(17.3%) 0(0.0%) real->precision ...building/src/programs.rkt:94:11 ??? [21] 75.3% ??? [23] 24.7% ----------------------------------------------------------------------------- loop [15] 100.0% [19] 582(13.4%) 196(4.5%) ??? ...tract/private/arrow-higher-order.rkt:379:33 ival-sub [22] 34.7% ival-div [24] 31.6% ----------------------------------------------------------------------------- ??? [17] 100.0% [20] 1474(33.8%) 0(0.0%) for-loop ...-rebuilding/src/core/simplify.rkt:76:2 for-loop [25] 100.0% ----------------------------------------------------------------------------- .../more-scheme.rkt:261:28 [4] 25.5% real->precision [18] 74.5% [21] 762(17.5%) 562(12.9%) ??? ...tract/private/arrow-higher-order.rkt:375:33 bf [27] 26.2% ----------------------------------------------------------------------------- ??? [19] 100.0% [22] 202(4.6%) 0(0.0%) ival-sub ...h-rebuilding/src/biginterval.rkt:108:0 bfdiv [26] 100.0% ----------------------------------------------------------------------------- real->precision [18] 100.0% [23] 186(4.3%) 0(0.0%) ??? ...ects/racket/contract/private/guts.rkt:770:8 bigvalue? [28] 100.0% ----------------------------------------------------------------------------- ??? [19] 100.0% [24] 184(4.2%) 0(0.0%) ival-div ...h-rebuilding/src/biginterval.rkt:156:0 bfdiv [26] 100.0% ----------------------------------------------------------------------------- for-loop [20] 100.0% [25] 1474(33.8%) 0(0.0%) for-loop ...-rebuilding/src/core/simplify.rkt:80:4 ??? [29] 100.0% ----------------------------------------------------------------------------- ival-div [24] 47.7% ival-sub [22] 52.3% [26] 386(8.9%) 0(0.0%) bfdiv ...-lib/math/private/bigfloat/mpfr.rkt:835:4 ??? [30] 100.0% ----------------------------------------------------------------------------- ??? [21] 100.0% [27] 200(4.6%) 200(4.6%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:615:2 ----------------------------------------------------------------------------- ??? [23] 100.0% [28] 186(4.3%) 186(4.3%) bigvalue? ...-rebuilding/src/syntax/types.rkt:19:0 ----------------------------------------------------------------------------- for-loop [25] 100.0% [29] 1474(33.8%) 0(0.0%) ??? ...fy-regraph-rebuilding/regraph/main.rkt:45:0 for-loop [31] 74.9% find-matches [32] 25.1% ----------------------------------------------------------------------------- bfdiv [26] 100.0% [30] 386(8.9%) 386(8.9%) ??? /opt/racket-7.5/collects/ffi/unsafe.rkt:938:4 ----------------------------------------------------------------------------- ??? [29] 100.0% [31] 1104(25.3%) 0(0.0%) for-loop ...graph-rebuilding/regraph/main.rkt:48:2 for-loop [33] 100.0% ----------------------------------------------------------------------------- ??? [29] 100.0% [32] 370(8.5%) 0(0.0%) find-matches ...h-rebuilding/regraph/main.rkt:37:0 for-loop [34] 100.0% ----------------------------------------------------------------------------- for-loop [31] 100.0% [33] 1104(25.3%) 0(0.0%) for-loop ...graph-rebuilding/regraph/main.rkt:51:4 merge-egraph-nodes! [35] 50.0% dedup-children! [36] 50.0% ----------------------------------------------------------------------------- for-loop [34] 50.0% find-matches [32] 50.0% [34] 370(8.5%) 0(0.0%) for-loop ...graph-rebuilding/regraph/main.rkt:39:2 for-loop [34] 50.0% match-e [37] 50.0% ----------------------------------------------------------------------------- for-loop [33] 100.0% [35] 552(12.7%) 0(0.0%) merge-egraph-nodes! ...ng/regraph/egraph.rkt:169:0 loop! [44] 100.0% ----------------------------------------------------------------------------- for-loop [33] 100.0% [36] 552(12.7%) 0(0.0%) dedup-children! ...uilding/regraph/enode.rkt:175:0 loop [38] 100.0% ----------------------------------------------------------------------------- for-loop [42] 24.9% for-loop [34] 75.1% [37] 370(8.5%) 184(4.2%) match-e ...raph-rebuilding/regraph/ematch.rkt:35:0 for-loop [39] 75.1% ----------------------------------------------------------------------------- dedup-children! [36] 23.7% loop [38] 76.3% [38] 552(12.7%) 0(0.0%) loop ...racket-7.5/collects/racket/list.rkt:417:34 loop [38] 76.3% loop [40] 23.7% ----------------------------------------------------------------------------- match-e [37] 100.0% [39] 370(8.5%) 0(0.0%) for-loop ...ph-rebuilding/regraph/ematch.rkt:41:11 procedure-reduce-keyword-arity-mask [41] 50.3% for-loop [42] 49.7% ----------------------------------------------------------------------------- loop! [44] 4.8% for-loop [13] 13.0% loop [40] 19.7% ??? [16] 25.7% loop [38] 36.8% [40] 1502(34.5%) 748(17.2%) loop (unknown source) sample-multi-bounded [43] 25.7% loop [40] 19.7% loop! [44] 4.8% ----------------------------------------------------------------------------- for-loop [39] 100.0% [41] 186(4.3%) 186(4.3%) procedure-reduce-keyword-arity-mask ....rkt:1597:4 ----------------------------------------------------------------------------- for-loop [39] 100.0% [42] 184(4.2%) 0(0.0%) for-loop ...ph-rebuilding/regraph/ematch.rkt:47:37 match-e [37] 100.0% ----------------------------------------------------------------------------- ??? [16] 49.9% loop [40] 50.1% [43] 770(17.7%) 0(0.0%) sample-multi-bounded ...ilding/src/points.rkt:14:0 random-ranges [45] 100.0% ----------------------------------------------------------------------------- loop [40] 33.3% merge-egraph-nodes! [35] 66.7% [44] 552(12.7%) 0(0.0%) loop! ...egraph-rebuilding/regraph/enode.rkt:167:2 set [46] 66.7% loop [40] 33.3% ----------------------------------------------------------------------------- sample-multi-bounded [43] 100.0% [45] 770(17.7%) 370(8.5%) random-ranges ...h-rebuilding/src/common.rkt:164:0 cons/c [47] 51.9% ----------------------------------------------------------------------------- loop! [44] 100.0% [46] 552(12.7%) 552(12.7%) set ...collects/racket/private/set-types.rkt:992:0 ----------------------------------------------------------------------------- random-ranges [45] 100.0% [47] 400(9.2%) 0(0.0%) cons/c .../racket/contract/private/guts.rkt:500:15 coerce-contract [48] 100.0% ----------------------------------------------------------------------------- cons/c [47] 100.0% [48] 400(9.2%) 0(0.0%) coerce-contract ...contract/private/guts.rkt:307:0 coerce-contract/f [49] 100.0% ----------------------------------------------------------------------------- coerce-contract [48] 100.0% [49] 400(9.2%) 0(0.0%) coerce-contract/f ...ntract/private/guts.rkt:372:0 coerce-simple-value [50] 100.0% ----------------------------------------------------------------------------- coerce-contract/f [49] 100.0% [50] 400(9.2%) 400(9.2%) coerce-simple-value ...ract/private/guts.rkt:385:0 -----------------------------------------------------------------------------