Profiling results ----------------- Total cpu time observed: 18300ms (out of 18376ms) Number of samples taken: 82 (once every 223ms) ======================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ======================================================================== [1] 18300(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [4] 100.0% ------------------------------------------------------------------------ hash-ref! [27] 100.0% [2] 1272(7.0%) 0(0.0%) ??? ...s/herbie/egg-cleanup/src/programs.rkt:177:5 loop [22] 100.0% ------------------------------------------------------------------------ finish-bodys [51] 100.0% [3] 200(1.1%) 0(0.0%) for-loop (unknown source) finish-bodys [51] 96.2% loop [22] 3.8% ------------------------------------------------------------------------ ??? [1] 100.0% [4] 18300(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [5] 100.0% ------------------------------------------------------------------------ profile-thunk16 [4] 100.0% [5] 18300(100.0%) 0(0.0%) run ...et-7.5/share/pkgs/profile-lib/main.rkt:39:2 .../more-scheme.rkt:261:28 [6] 100.0% ------------------------------------------------------------------------ run [5] 100.0% [6] 18300(100.0%) 0(0.0%) .../more-scheme.rkt:261:28 (unknown source) loop [9] 90.5% run-improve47 [7] 8.2% eval-errors [8] 1.3% ------------------------------------------------------------------------ .../more-scheme.rkt:261:28 [6] 100.0% [7] 1502(8.2%) 0(0.0%) run-improve47 ...gg-cleanup/src/mainloop.rkt:346:0 loop [9] 71.4% simplify! [11] 15.3% for-loop [12] 13.3% ------------------------------------------------------------------------ .../more-scheme.rkt:261:28 [6] 100.0% [8] 240(1.3%) 0(0.0%) eval-errors ...ie/egg-cleanup/src/points.rkt:179:0 for-loop [10] 100.0% ------------------------------------------------------------------------ run-improve47 [7] 6.1% .../more-scheme.rkt:261:28 [6] 93.9% [9] 17630(96.3%) 0(0.0%) loop ...es/herbie/egg-cleanup/src/points.rkt:277:2 loop [13] 37.1% loop [18] 31.4% make-exacts-walkup [17] 30.4% for-loop [15] 1.1% ------------------------------------------------------------------------ eval-errors [8] 100.0% [10] 240(1.3%) 240(1.3%) for-loop ...erbie/egg-cleanup/src/points.rkt:181:2 ------------------------------------------------------------------------ run-improve47 [7] 100.0% [11] 230(1.3%) 0(0.0%) simplify! ...ie/egg-cleanup/src/mainloop.rkt:224:0 ??? [14] 100.0% ------------------------------------------------------------------------ run-improve47 [7] 100.0% [12] 200(1.1%) 0(0.0%) for-loop ...bie/egg-cleanup/src/mainloop.rkt:365:4 run-iter! [16] 100.0% ------------------------------------------------------------------------ loop [9] 100.0% [13] 6538(35.7%) 0(0.0%) loop ...es/herbie/egg-cleanup/src/points.rkt:240:2 make-exacts-walkup [17] 55.1% loop [18] 44.9% ------------------------------------------------------------------------ simplify! [11] 100.0% [14] 230(1.3%) 0(0.0%) ??? ...rbie/egg-cleanup/src/core/simplify.rkt:35:0 egraph-run [19] 100.0% ------------------------------------------------------------------------ loop [9] 100.0% [15] 200(1.1%) 0(0.0%) for-loop ...rbie/egg-cleanup/src/points.rkt:297:19 ??? [20] 100.0% ------------------------------------------------------------------------ for-loop [12] 100.0% [16] 200(1.1%) 0(0.0%) run-iter! ...ie/egg-cleanup/src/mainloop.rkt:327:0 localize! [21] 100.0% ------------------------------------------------------------------------ loop [13] 40.2% loop [9] 59.8% [17] 8966(49.0%) 0(0.0%) make-exacts-walkup ...cleanup/src/points.rkt:218:0 loop [22] 83.6% eval-prog [23] 16.4% ------------------------------------------------------------------------ loop [13] 34.7% loop [9] 65.3% [18] 8464(46.3%) 0(0.0%) loop ...es/herbie/egg-cleanup/src/points.rkt:223:4 loop [22] 97.4% composed [26] 2.6% ------------------------------------------------------------------------ ??? [14] 100.0% [19] 230(1.3%) 0(0.0%) egraph-run ...gg-cleanup/src/core/eggmath.rkt:51:0 egraph-add-exprs [24] 100.0% ------------------------------------------------------------------------ for-loop [15] 100.0% [20] 200(1.1%) 200(1.1%) ??? ...ies/herbie/egg-cleanup/src/points.rkt:106:2 ------------------------------------------------------------------------ run-iter! [16] 100.0% [21] 200(1.1%) 0(0.0%) localize! ...ie/egg-cleanup/src/mainloop.rkt:127:0 localize-error [25] 100.0% ------------------------------------------------------------------------ make-exacts-walkup [17] 0.1% loop [18] 0.5% for-loop [3] 1.2% ??? [2] 7.4% loop [22] 90.8% [22] 17214(94.1%) 240(1.3%) loop (unknown source) loop [22] 90.8% hash-ref! [27] 7.4% expand-id-application-form17 [29] 1.2% composed [26] 0.6% ------------------------------------------------------------------------ make-exacts-walkup [17] 100.0% [23] 1472(8.0%) 0(0.0%) eval-prog ...bie/egg-cleanup/src/programs.rkt:83:0 hash-ref! [27] 86.4% temp85_0 [30] 13.6% ------------------------------------------------------------------------ egraph-run [19] 100.0% [24] 230(1.3%) 0(0.0%) egraph-add-exprs ...nup/src/core/eggmath.rkt:113:0 ??? [28] 100.0% ------------------------------------------------------------------------ localize! [21] 100.0% [25] 200(1.1%) 0(0.0%) localize-error ...eanup/src/core/localize.rkt:65:0 hash-ref! [27] 100.0% ------------------------------------------------------------------------ loop [18] 1.4% loop [22] 98.6% [26] 15718(85.9%) 242(1.3%) composed ...ollects/racket/private/list.rkt:321:16 ??? [31] 98.5% ------------------------------------------------------------------------ localize-error [25] 1.4% eval-prog [23] 12.0% map [39] 12.2% loop [22] 74.4% [27] 1472(8.0%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 ??? [2] 74.4% parse-loop30 [35] 13.6% bigfloat-hash [32] 12.0% ------------------------------------------------------------------------ egraph-add-exprs [24] 100.0% [28] 230(1.3%) 0(0.0%) ??? ...rbie/egg-cleanup/src/core/simplify.rkt:93:6 for-loop [33] 100.0% ------------------------------------------------------------------------ loop [22] 100.0% [29] 200(1.1%) 0(0.0%) expand-id-application-form17 (unknown source) binding-lookup52 [34] 100.0% ------------------------------------------------------------------------ eval-prog [23] 100.0% [30] 200(1.1%) 0(0.0%) temp85_0 (unknown source) compile15 [36] 100.0% ------------------------------------------------------------------------ composed [26] 100.0% [31] 15476(84.6%) 0(0.0%) ??? ...s/herbie/egg-cleanup/src/programs.rkt:115:2 ??? [44] 100.0% ------------------------------------------------------------------------ hash-ref! [27] 100.0% [32] 1272(7.0%) 200(1.1%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:168:0 bfcanonicalize [37] 51.6% sig+exp->bigfloat [38] 32.7% ------------------------------------------------------------------------ ??? [28] 100.0% [33] 230(1.3%) 230(1.3%) for-loop ...gg-cleanup/src/core/simplify.rkt:112:2 ------------------------------------------------------------------------ expand-id-application-form17 [29]100.0% [34] 200(1.1%) 200(1.1%) binding-lookup52 (unknown source) ------------------------------------------------------------------------ hash-ref! [27] 100.0% [35] 200(1.1%) 0(0.0%) parse-loop30 (unknown source) map [39] 100.0% ------------------------------------------------------------------------ temp85_0 [30] 100.0% [36] 200(1.1%) 0(0.0%) compile15 (unknown source) temp91_0 [40] 100.0% ------------------------------------------------------------------------ bigfloat-hash [32] 100.0% [37] 656(3.6%) 656(3.6%) bfcanonicalize .../private/bigfloat/mpfr.rkt:158:0 ------------------------------------------------------------------------ bigfloat-hash [32] 100.0% [38] 416(2.3%) 0(0.0%) sig+exp->bigfloat ...ivate/bigfloat/mpfr.rkt:401:0 new-mpfr [58] 51.4% integer->mpz [41] 48.6% ------------------------------------------------------------------------ parse-loop30 [35] 100.0% [39] 200(1.1%) 0(0.0%) map ...t-7.5/collects/racket/private/map.rkt:35:13 hash-ref! [27] 90.0% loop [42] 10.0% ------------------------------------------------------------------------ compile15 [36] 100.0% [40] 200(1.1%) 0(0.0%) temp91_0 (unknown source) temp118_0 [43] 100.0% ------------------------------------------------------------------------ sig+exp->bigfloat [38] 100.0% [41] 202(1.1%) 202(1.1%) integer->mpz ...math/private/bigfloat/gmp.rkt:87:0 ------------------------------------------------------------------------ map [39] 20.0% loop [42] 80.0% [42] 200(1.1%) 0(0.0%) loop ...-7.5/collects/racket/private/map.rkt:40:19 loop [42] 80.0% ??? [44] 20.0% ------------------------------------------------------------------------ temp91_0 [40] 100.0% [43] 200(1.1%) 0(0.0%) temp118_0 (unknown source) expand-capturing-lifts [45] 100.0% ------------------------------------------------------------------------ loop [42] 1.3% ??? [31] 98.7% [44] 15676(85.7%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:379:33 bf-complex-mult [46] 91.8% bf-complex-add [47] 8.2% ------------------------------------------------------------------------ temp118_0 [43] 100.0% [45] 200(1.1%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [48] 100.0% ------------------------------------------------------------------------ ??? [44] 100.0% [46] 14390(78.6%) 902(4.9%) bf-complex-mult ...cleanup/src/bigcomplex.rkt:30:0 ??? [52] 41.5% bf* [49] 34.4% ...higher-order.rkt:379:33 [50] 17.8% ------------------------------------------------------------------------ ??? [44] 100.0% [47] 1286(7.0%) 646(3.5%) bf-complex-add ...-cleanup/src/bigcomplex.rkt:23:0 ??? [52] 49.8% ------------------------------------------------------------------------ expand-capturing-lifts [45] 100.0% [48] 200(1.1%) 0(0.0%) lambda-clause-expander (unknown source) finish-bodys [51] 100.0% ------------------------------------------------------------------------ bf-complex-mult [46] 100.0% [49] 4946(27.0%) 242(1.3%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [52] 95.1% ------------------------------------------------------------------------ bf-complex-mult [46] 100.0% [50] 2566(14.0%) 688(3.8%) ...higher-order.rkt:379:33 (unknown source) ??? [53] 73.2% ------------------------------------------------------------------------ lambda-clause-expander [48] 3.8% for-loop [3] 96.2% [51] 200(1.1%) 0(0.0%) finish-bodys (unknown source) for-loop [3] 100.0% ------------------------------------------------------------------------ bf-complex-add [47] 5.7% bf* [49] 41.6% bf-complex-mult [46] 52.8% [52] 11320(61.9%) 1798(9.8%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [54] 78.3% bfneg [55] 5.8% ------------------------------------------------------------------------ ...higher-order.rkt:379:33 [50] 100.0% [53] 1878(10.3%) 1632(8.9%) ??? ...cts/racket/contract/private/list.rkt:209:10 ??? [56] 13.1% ------------------------------------------------------------------------ ??? [52] 100.0% [54] 8864(48.4%) 882(4.8%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:835:4 ??? [57] 50.1% new-mpfr [58] 39.9% ------------------------------------------------------------------------ ??? [52] 100.0% [55] 658(3.6%) 0(0.0%) bfneg ...-lib/math/private/bigfloat/mpfr.rkt:632:4 new-mpfr [58] 63.5% ??? [57] 36.5% ------------------------------------------------------------------------ ??? [53] 100.0% [56] 246(1.3%) 246(1.3%) ??? ...ects/racket/contract/private/guts.rkt:770:8 ------------------------------------------------------------------------ bfneg [55] 5.1% bfmul [54] 94.9% [57] 4684(25.6%) 4684(25.6%) ??? /opt/racket-7.5/collects/ffi/unsafe.rkt:938:4 ------------------------------------------------------------------------ sig+exp->bigfloat [38] 5.1% bfneg [55] 10.0% bfmul [54] 84.8% [58] 4170(22.8%) 4170(22.8%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:348:0 ------------------------------------------------------------------------