Profiling results ----------------- Total cpu time observed: 12708ms (out of 13240ms) Number of samples taken: 37 (once every 343ms) ======================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ======================================================================== run [8] 48.8% [1] 12708(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [3] 48.8% run-improve48 [4] 29.0% prepare-points [5] 18.4% loop [6] 3.9% ------------------------------------------------------------------------ ??? [37] 100.0% [2] 662(5.2%) 0(0.0%) loop ...clean-out-code/src/core/simplify.rkt:187:2 pass [7] 100.0% ------------------------------------------------------------------------ ??? [1] 100.0% [3] 12392(97.5%) 0(0.0%) profile-thunk16 ...t/pkgs/profile-lib/main.rkt:9:0 run [8] 100.0% ------------------------------------------------------------------------ ??? [1] 100.0% [4] 7044(55.4%) 0(0.0%) run-improve48 ...n-out-code/src/mainloop.rkt:286:0 for-loop [9] 76.1% for-loop [11] 23.9% ------------------------------------------------------------------------ ??? [1] 100.0% [5] 4670(36.7%) 0(0.0%) prepare-points ...an-out-code/src/points.rkt:156:0 loop [10] 34.9% make-exacts* [14] 32.8% loop [15] 32.3% ------------------------------------------------------------------------ ??? [1] 100.0% [6] 994(7.8%) 0(0.0%) loop ...herbie/clean-out-code/src/points.rkt:180:2 loop [10] 34.6% make-exacts* [14] 33.2% loop [15] 32.2% ------------------------------------------------------------------------ loop [2] 100.0% [7] 662(5.2%) 0(0.0%) pass ...clean-out-code/src/core/simplify.rkt:177:2 for-loop [12] 100.0% ------------------------------------------------------------------------ profile-thunk16 [3] 100.0% [8] 12392(97.5%) 0(0.0%) run ...share/racket/pkgs/profile-lib/main.rkt:39:2 ??? [1] 100.0% ------------------------------------------------------------------------ run-improve48 [4] 100.0% [9] 5364(42.2%) 0(0.0%) for-loop .../clean-out-code/src/mainloop.rkt:303:8 run-iter! [13] 100.0% ------------------------------------------------------------------------ loop [6] 17.4% prepare-points [5] 82.6% [10] 1976(15.5%) 0(0.0%) loop ...herbie/clean-out-code/src/points.rkt:124:2 make-exacts* [14] 65.2% loop [15] 34.8% ------------------------------------------------------------------------ run-improve48 [4] 100.0% [11] 1680(13.2%) 0(0.0%) for-loop ...clean-out-code/src/mainloop.rkt:298:29 ??? [37] 100.0% ------------------------------------------------------------------------ pass [7] 100.0% [12] 662(5.2%) 0(0.0%) for-loop ...-out-code/src/core/simplify.rkt:180:29 for-loop [16] 50.5% argmin [17] 49.5% ------------------------------------------------------------------------ for-loop [9] 100.0% [13] 5364(42.2%) 0(0.0%) run-iter! ...clean-out-code/src/mainloop.rkt:267:0 simplify! [19] 87.4% choose-best-alt! [21] 12.6% ------------------------------------------------------------------------ loop [6] 10.5% loop [10] 40.9% prepare-points [5] 48.6% [14] 3148(24.8%) 0(0.0%) make-exacts* ...lean-out-code/src/points.rkt:104:0 loop [18] 67.3% eval-prog [20] 32.7% ------------------------------------------------------------------------ loop [6] 12.7% loop [10] 27.3% prepare-points [5] 59.9% [15] 2516(19.8%) 0(0.0%) loop ...herbie/clean-out-code/src/points.rkt:107:4 loop [18] 100.0% ------------------------------------------------------------------------ for-loop [12] 100.0% [16] 334(2.6%) 334(2.6%) for-loop ...-out-code/src/core/simplify.rkt:167:13 ------------------------------------------------------------------------ for-loop [12] 100.0% [17] 328(2.6%) 0(0.0%) argmin ...re/racket/collects/racket/list.rkt:743:0 for-loop [22] 100.0% ------------------------------------------------------------------------ loop [15] 0.6% ??? [30] 3.5% make-exacts* [14] 8.4% loop [18] 87.6% [18] 4978(39.2%) 330(2.6%) loop ...cket/collects/racket/private/map.rkt:25:19 loop [18] 87.6% ??? [24] 8.9% hash-ref! [25] 3.5% ??? [31] 0.0% ------------------------------------------------------------------------ run-iter! [13] 100.0% [19] 4686(36.9%) 0(0.0%) simplify! ...clean-out-code/src/mainloop.rkt:181:0 for-loop [23] 100.0% ------------------------------------------------------------------------ make-exacts* [14] 100.0% [20] 1030(8.1%) 0(0.0%) eval-prog ...clean-out-code/src/programs.rkt:109:0 hash-ref! [25] 100.0% ------------------------------------------------------------------------ run-iter! [13] 100.0% [21] 678(5.3%) 0(0.0%) choose-best-alt! ...ut-code/src/mainloop.rkt:113:0 ??? [59] 100.0% ------------------------------------------------------------------------ argmin [17] 100.0% [22] 328(2.6%) 0(0.0%) for-loop .../clean-out-code/src/programs.rkt:169:2 ??? [37] 100.0% ------------------------------------------------------------------------ simplify! [19] 100.0% [23] 4686(36.9%) 0(0.0%) for-loop .../clean-out-code/src/mainloop.rkt:185:6 for-loop [26] 100.0% ------------------------------------------------------------------------ for-loop [48] 17.1% loop [18] 82.9% [24] 3954(31.1%) 726(5.7%) ??? ...erbie/clean-out-code/src/programs.rkt:129:2 ??? [27] 46.3% ??? [28] 35.4% ------------------------------------------------------------------------ loop [18] 16.7% eval-prog [20] 83.3% [25] 1030(8.1%) 0(0.0%) hash-ref! ...racket/private/more-scheme.rkt:373:13 bigfloat-hash [29] 83.3% ??? [30] 16.7% ------------------------------------------------------------------------ for-loop [23] 100.0% [26] 4686(36.9%) 0(0.0%) for-loop .../clean-out-code/src/mainloop.rkt:209:8 ??? [31] 100.0% ------------------------------------------------------------------------ ??? [24] 100.0% [27] 1830(14.4%) 0(0.0%) ??? ...contract/private/../../private/kw.rkt:761:9 real->bigfloat [32] 100.0% ------------------------------------------------------------------------ ??? [24] 100.0% [28] 1398(11.0%) 770(6.1%) ??? ...acket/collects/racket/private/kw.rkt:444:14 ...higher-order.rkt:346:33 [35] 23.3% ??? [36] 21.6% ------------------------------------------------------------------------ hash-ref! [25] 100.0% [29] 1030(8.1%) 0(0.0%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:165:0 bfcanonicalize [33] 64.1% sig+exp->bigfloat [34] 35.9% ------------------------------------------------------------------------ hash-ref! [25] 100.0% [30] 344(2.7%) 0(0.0%) ??? ...erbie/clean-out-code/src/programs.rkt:153:5 loop [18] 100.0% ------------------------------------------------------------------------ ??? [65] 10.6% loop [18] 16.1% ??? [37] 36.7% for-loop [26] 36.7% [31] 6392(50.3%) 380(3.0%) ??? ...tract/private/arrow-higher-order.rkt:342:33 ??? [37] 83.4% best-alt [38] 10.6% ------------------------------------------------------------------------ ??? [27] 100.0% [32] 1830(14.4%) 1148(9.0%) real->bigfloat .../private/bigfloat/mpfr.rkt:451:0 ??? [43] 37.3% ------------------------------------------------------------------------ bigfloat-hash [29] 100.0% [33] 660(5.2%) 660(5.2%) bfcanonicalize .../private/bigfloat/mpfr.rkt:155:0 ------------------------------------------------------------------------ bigfloat-hash [29] 100.0% [34] 370(2.9%) 370(2.9%) sig+exp->bigfloat ...ivate/bigfloat/mpfr.rkt:385:0 ------------------------------------------------------------------------ ??? [28] 100.0% [35] 326(2.6%) 0(0.0%) ...higher-order.rkt:346:33 (unknown source) ??? [68] 100.0% ------------------------------------------------------------------------ ??? [28] 100.0% [36] 302(2.4%) 302(2.4%) ??? ...rivate/unconstrained-domain-arrow.rkt:40:13 ------------------------------------------------------------------------ for-loop [22] 1.6% get-sigs [57] 3.1% for-loop [11] 24.0% ??? [31] 71.4% [37] 7014(55.2%) 968(7.6%) ??? ...llects/racket/private/norm-define.rkt:53:83 iterate-egraph!7 [39] 51.1% ??? [31] 31.1% ??? [41] 4.7% loop [2] 3.9% ??? [69] 1.6% ------------------------------------------------------------------------ ??? [31] 100.0% [38] 678(5.3%) 0(0.0%) best-alt .../clean-out-code/src/mainloop.rkt:108:0 argmins [40] 100.0% ------------------------------------------------------------------------ ??? [37] 100.0% [39] 5704(44.9%) 0(0.0%) iterate-egraph!7 ...ode/src/core/simplify.rkt:55:0 one-iter [42] 100.0% ------------------------------------------------------------------------ best-alt [38] 100.0% [40] 678(5.3%) 0(0.0%) argmins ...bie/clean-out-code/src/common.rkt:142:0 ??? [44] 100.0% ------------------------------------------------------------------------ ??? [37] 100.0% [41] 328(2.6%) 0(0.0%) ??? ...-racket/utils/simple-result-arrow.rkt:60:15 ??? [43] 100.0% ------------------------------------------------------------------------ iterate-egraph!7 [39] 100.0% [42] 5704(44.9%) 0(0.0%) one-iter ...an-out-code/src/core/simplify.rkt:66:0 for-loop [45] 100.0% ------------------------------------------------------------------------ ??? [41] 32.5% real->bigfloat [32] 67.5% [43] 1010(7.9%) 1010(7.9%) ??? .../share/racket/collects/ffi/unsafe.rkt:861:4 ------------------------------------------------------------------------ argmins [40] 100.0% [44] 678(5.3%) 0(0.0%) ??? ...rbie/clean-out-code/src/mainloop.rkt:110:19 errors [46] 100.0% ------------------------------------------------------------------------ one-iter [42] 100.0% [45] 5704(44.9%) 662(5.2%) for-loop ...n-out-code/src/core/simplify.rkt:116:2 for-loop [47] 88.4% ------------------------------------------------------------------------ ??? [44] 100.0% [46] 678(5.3%) 0(0.0%) errors ...rbie/clean-out-code/src/points.rkt:203:0 for-loop [48] 100.0% ------------------------------------------------------------------------ for-loop [45] 100.0% [47] 5042(39.7%) 0(0.0%) for-loop ...n-out-code/src/core/simplify.rkt:98:10 substitute-e [49] 59.1% mk-enode! [51] 27.9% merge-egraph-nodes! [50] 13.0% ------------------------------------------------------------------------ errors [46] 100.0% [48] 678(5.3%) 0(0.0%) for-loop ...ie/clean-out-code/src/points.rkt:206:4 ??? [24] 100.0% ------------------------------------------------------------------------ substitute-e [49] 10.8% for-loop [47] 89.2% [49] 2978(23.4%) 0(0.0%) substitute-e ...-out-code/src/core/ematch.rkt:71:0 mk-enode! [51] 89.2% substitute-e [49] 10.8% ------------------------------------------------------------------------ for-loop [47] 100.0% [50] 656(5.2%) 316(2.5%) merge-egraph-nodes! ...e/src/core/egraph.rkt:153:0 enode-merge! [52] 51.8% ------------------------------------------------------------------------ for-loop [47] 32.1% substitute-e [49] 67.9% [51] 4386(34.5%) 340(2.7%) mk-enode! ...an-out-code/src/core/egraph.rkt:102:0 new-enode [53] 92.2% ------------------------------------------------------------------------ merge-egraph-nodes! [50] 100.0% [52] 340(2.7%) 0(0.0%) enode-merge! ...-out-code/src/core/enode.rkt:129:0 adopt-enode! [54] 100.0% ------------------------------------------------------------------------ mk-enode! [51] 100.0% [53] 4046(31.8%) 0(0.0%) new-enode ...lean-out-code/src/core/enode.rkt:97:0 type-of-enode-expr [55] 100.0% ------------------------------------------------------------------------ enode-merge! [52] 100.0% [54] 340(2.7%) 0(0.0%) adopt-enode! ...-out-code/src/core/enode.rkt:105:0 custom-set-union [56] 100.0% ------------------------------------------------------------------------ new-enode [53] 100.0% [55] 4046(31.8%) 0(0.0%) type-of-enode-expr ...code/src/core/enode.rkt:68:0 get-sigs [57] 100.0% ------------------------------------------------------------------------ adopt-enode! [54] 100.0% [56] 340(2.7%) 0(0.0%) custom-set-union ...et/private/set-types.rkt:168:0 for-loop [58] 100.0% ------------------------------------------------------------------------ type-of-enode-expr [55] 100.0% [57] 4046(31.8%) 0(0.0%) get-sigs .../clean-out-code/src/type-check.rkt:5:0 ??? [60] 41.5% ??? [59] 26.0% ...-code/src/common.rkt:48:2 [61] 16.5% ??? [37] 16.0% ------------------------------------------------------------------------ custom-set-union [56] 100.0% [58] 340(2.7%) 0(0.0%) for-loop ...cts/racket/private/set-types.rkt:174:3 for-loop [62] 100.0% ------------------------------------------------------------------------ choose-best-alt! [21] 39.2% get-sigs [57] 60.8% [59] 1730(13.6%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:357:18 hash-has-key? [64] 60.8% ??? [65] 39.2% ------------------------------------------------------------------------ get-sigs [57] 100.0% [60] 1678(13.2%) 0(0.0%) ??? ...ts/racket/contract/private/arr-i.rkt:804:10 for-loop [63] 80.6% ??? [67] 19.4% ------------------------------------------------------------------------ get-sigs [57] 100.0% [61] 668(5.3%) 0(0.0%) ...-code/src/common.rkt:48:2 ...e/arr-i.rkt:906:19 ??? [66] 53.3% ??? [77] 46.7% ------------------------------------------------------------------------ for-loop [58] 100.0% [62] 340(2.7%) 340(2.7%) for-loop ...cts/racket/private/set-types.rkt:178:5 ------------------------------------------------------------------------ ??? [60] 100.0% [63] 1352(10.6%) 0(0.0%) for-loop ...racket/contract/private/hash.rkt:239:6 ??? [68] 100.0% ------------------------------------------------------------------------ ??? [59] 100.0% [64] 1052(8.3%) 0(0.0%) hash-has-key? ...et/private/more-scheme.rkt:368:13 ??? [69] 100.0% ------------------------------------------------------------------------ ??? [59] 100.0% [65] 678(5.3%) 0(0.0%) ??? .../clean-out-code/src/core/alt-table.rkt:54:0 ??? [31] 100.0% ------------------------------------------------------------------------ ...-code/src/common.rkt:48:2 [61]100.0% [66] 356(2.8%) 0(0.0%) ??? ...s/racket/contract/private/arr-i.rkt:1268:39 return/no-unsupplied [70] 100.0% ------------------------------------------------------------------------ ??? [60] 100.0% [67] 326(2.6%) 0(0.0%) ??? ...ects/racket/contract/private/hash.rkt:217:4 ??? [71] 100.0% ------------------------------------------------------------------------ ??? [77] 15.7% ...higher-order.rkt:346:33 [35] 16.4% for-loop [63] 67.9% [68] 1990(15.7%) 0(0.0%) ??? ...cts/racket/contract/private/list.rkt:187:10 ??? [72] 67.9% ??? [78] 16.4% for-loop [75] 15.7% ------------------------------------------------------------------------ ??? [37] 23.8% hash-has-key? [64] 76.2% [69] 1380(10.9%) 1030(8.1%) ??? ...ects/racket/contract/private/list.rkt:737:4 for-loop [73] 25.4% ------------------------------------------------------------------------ ??? [66] 100.0% [70] 356(2.8%) 356(2.8%) return/no-unsupplied ...ct/private/arr-i.rkt:533:0 ------------------------------------------------------------------------ ??? [67] 100.0% [71] 326(2.6%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:169:2 ??? [74] 100.0% ------------------------------------------------------------------------ ??? [68] 100.0% [72] 1352(10.6%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:686:7 ??? [78] 100.0% ------------------------------------------------------------------------ ??? [69] 100.0% [73] 350(2.8%) 350(2.8%) for-loop ...acket/contract/private/list.rkt:743:12 ------------------------------------------------------------------------ ??? [71] 100.0% [74] 326(2.6%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:678:5 for-loop [76] 100.0% ------------------------------------------------------------------------ ??? [68] 100.0% [75] 312(2.5%) 0(0.0%) for-loop ...acket/contract/private/list.rkt:190:15 ??? [77] 100.0% ------------------------------------------------------------------------ ??? [74] 100.0% [76] 326(2.6%) 0(0.0%) for-loop ...racket/contract/private/list.rkt:680:9 add-list-context [79] 100.0% ------------------------------------------------------------------------ for-loop [75] 50.0% ...-code/src/common.rkt:48:2 [61] 50.0% [77] 312(2.5%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:342:4 ??? [68] 50.0% ??? [78] 50.0% ------------------------------------------------------------------------ ??? [77] 15.7% ??? [68] 16.4% ??? [72] 67.9% [78] 1990(15.7%) 1678(13.2%) ??? ...ects/racket/contract/private/guts.rkt:644:8 contract? [80] 15.7% ------------------------------------------------------------------------ for-loop [76] 100.0% [79] 326(2.6%) 326(2.6%) add-list-context ...ontract/private/list.rkt:752:0 ------------------------------------------------------------------------ ??? [78] 100.0% [80] 312(2.5%) 312(2.5%) contract? ...acket/contract/private/guts.rkt:109:0 ------------------------------------------------------------------------