Profiling results ----------------- Total cpu time observed: 33002ms (out of 33468ms) Number of samples taken: 130 (once every 254ms) (Hiding functions with self<1.0% and local<2.0%: 4 of 109 hidden) ================================================================================ Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ================================================================================ ??? [6] 100.0% [1] 33002(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [3] 100.0% -------------------------------------------------------------------------------- matcher [51] 100.0% [2] 300(0.9%) 0(0.0%) ??? ...s/herbie/master/src/core/matcher.rkt:130:11 for-loop [5] 50.0% for-loop [4] 50.0% -------------------------------------------------------------------------------- profile-thunk16 [1] 100.0% [3] 33002(100.0%) 0(0.0%) run ...et-7.2/share/pkgs/profile-lib/main.rkt:39:2 ??? [6] 100.0% -------------------------------------------------------------------------------- ??? [2] 100.0% [4] 300(0.9%) 0(0.0%) for-loop ...rbie/master/src/core/matcher.rkt:106:4 foldl [71] 100.0% -------------------------------------------------------------------------------- ??? [2] 100.0% [5] 300(0.9%) 0(0.0%) for-loop ...bie/master/src/core/matcher.rkt:132:15 matcher [51] 100.0% -------------------------------------------------------------------------------- for-loop [105] 0.9% run [3] 49.6% [6] 33002(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 49.6% run-improve47 [7] 43.9% prepare-points [8] 4.4% ??? [19] 0.6% oracle-error [9] 0.5% eval-errors [10] 0.5% errors [101] 0.3% precision->real [11] 0.3% -------------------------------------------------------------------------------- ??? [6] 100.0% [7] 29176(88.4%) 0(0.0%) run-improve47 ...bie/master/src/mainloop.rkt:342:0 simplify! [18] 78.2% for-loop [12] 21.8% -------------------------------------------------------------------------------- ??? [6] 100.0% [8] 2928(8.9%) 0(0.0%) prepare-points ...rbie/master/src/points.rkt:146:0 loop [13] 100.0% -------------------------------------------------------------------------------- ??? [6] 100.0% [9] 320(1.0%) 0(0.0%) oracle-error ...herbie/master/src/points.rkt:188:0 for-loop [14] 100.0% -------------------------------------------------------------------------------- ??? [6] 100.0% [10] 306(0.9%) 0(0.0%) eval-errors .../herbie/master/src/points.rkt:179:0 for-loop [15] 100.0% -------------------------------------------------------------------------------- ??? [6] 100.0% [11] 272(0.8%) 0(0.0%) precision->real ...e/master/src/programs.rkt:92:33 infer-representation [28] 100.0% -------------------------------------------------------------------------------- run-improve47 [7] 100.0% [12] 6372(19.3%) 0(0.0%) for-loop ...s/herbie/master/src/mainloop.rkt:359:4 run-iter! [16] 100.0% -------------------------------------------------------------------------------- prepare-points [8] 100.0% [13] 2928(8.9%) 264(0.8%) loop ...ghtlies/herbie/master/src/points.rkt:122:4 loop [17] 82.5% infer-representation [28] 8.5% -------------------------------------------------------------------------------- oracle-error [9] 100.0% [14] 320(1.0%) 0(0.0%) for-loop ...ies/herbie/master/src/points.rkt:189:2 loop [69] 100.0% -------------------------------------------------------------------------------- eval-errors [10] 100.0% [15] 306(0.9%) 0(0.0%) for-loop ...ies/herbie/master/src/points.rkt:181:2 ??? [19] 100.0% -------------------------------------------------------------------------------- for-loop [12] 100.0% [16] 6372(19.3%) 0(0.0%) run-iter! .../herbie/master/src/mainloop.rkt:323:0 simplify! [18] 85.7% finalize-iter! [20] 9.6% gen-rewrites! [21] 4.7% -------------------------------------------------------------------------------- loop [13] 100.0% [17] 2416(7.3%) 628(1.9%) loop ...ightlies/herbie/master/src/points.rkt:81:2 ??? [19] 47.4% ??? [48] 13.6% ??? [67] 13.0% -------------------------------------------------------------------------------- run-iter! [16] 19.3% run-improve47 [7] 80.7% [18] 28266(85.6%) 0(0.0%) simplify! .../herbie/master/src/mainloop.rkt:220:0 ??? [22] 100.0% -------------------------------------------------------------------------------- for-loop [15] 14.8% ??? [6] 29.6% loop [17] 55.6% [19] 2062(6.2%) 306(0.9%) ??? ...htlies/herbie/master/src/programs.rkt:108:2 ??? [27] 41.7% ??? [23] 28.4% real->precision [24] 15.0% -------------------------------------------------------------------------------- run-iter! [16] 100.0% [20] 610(1.8%) 0(0.0%) finalize-iter! ...ie/master/src/mainloop.rkt:274:0 ??? [67] 100.0% -------------------------------------------------------------------------------- run-iter! [16] 100.0% [21] 300(0.9%) 0(0.0%) gen-rewrites! ...bie/master/src/mainloop.rkt:179:0 for-loop [25] 100.0% -------------------------------------------------------------------------------- simplify! [18] 100.0% [22] 28266(85.6%) 0(0.0%) ??? ...es/herbie/master/src/core/simplify.rkt:26:0 for-loop [26] 97.8% loop [33] 1.2% one-iter [31] 1.1% -------------------------------------------------------------------------------- ??? [19] 100.0% [23] 586(1.8%) 0(0.0%) ??? ...t-7.2/collects/racket/private/kw.rkt:594:14 ??? [29] 51.2% ??? [27] 48.8% -------------------------------------------------------------------------------- ??? [19] 100.0% [24] 310(0.9%) 0(0.0%) real->precision ...e/master/src/programs.rkt:87:33 infer-representation [28] 100.0% -------------------------------------------------------------------------------- gen-rewrites! [21] 100.0% [25] 300(0.9%) 0(0.0%) for-loop .../herbie/master/src/mainloop.rkt:187:11 rewrite-expression-head20 [30] 100.0% -------------------------------------------------------------------------------- ??? [22] 100.0% [26] 27638(83.7%) 0(0.0%) for-loop ...rbie/master/src/core/simplify.rkt:35:2 one-iter [31] 98.9% loop [33] 1.1% -------------------------------------------------------------------------------- ??? [23] 25.0% ??? [19] 75.0% [27] 1146(3.5%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:360:33 mk-ival [32] 75.0% ival-mult [35] 25.0% -------------------------------------------------------------------------------- loop [13] 29.9% precision->real [11] 32.8% real->precision [24] 37.3% [28] 830(2.5%) 310(0.9%) infer-representation .../master/src/float.rkt:15:0 ??? [36] 32.8% return/no-unsupplied [37] 29.9% -------------------------------------------------------------------------------- ??? [23] 100.0% [29] 300(0.9%) 300(0.9%) ??? .../herbie/master/src/syntax/syntax.rkt:207:17 -------------------------------------------------------------------------------- for-loop [25] 100.0% [30] 300(0.9%) 0(0.0%) rewrite-expression-head20 ...core/matcher.rkt:87:0 rewriter [34] 100.0% -------------------------------------------------------------------------------- ??? [22] 1.1% for-loop [26] 98.9% [31] 27634(83.7%) 0(0.0%) one-iter ...rbie/master/src/core/simplify.rkt:72:0 find-matches [38] 67.6% for-loop [39] 24.6% for-loop [40] 7.0% for-loop [45] 0.8% -------------------------------------------------------------------------------- ??? [27] 100.0% [32] 860(2.6%) 0(0.0%) mk-ival .../herbie/master/src/biginterval.rkt:57:0 bf [41] 100.0% -------------------------------------------------------------------------------- for-loop [26] 48.4% ??? [22] 51.6% [33] 632(1.9%) 0(0.0%) loop ...herbie/master/src/core/extraction.rkt:25:2 for-loop [42] 100.0% -------------------------------------------------------------------------------- matcher [51] 50.0% rewrite-expression-head20 [30] 50.0% [34] 300(0.9%) 0(0.0%) rewriter ...erbie/master/src/core/matcher.rkt:90:2 for-loop [43] 100.0% -------------------------------------------------------------------------------- ??? [27] 100.0% [35] 286(0.9%) 286(0.9%) ival-mult ...rbie/master/src/biginterval.rkt:112:0 -------------------------------------------------------------------------------- infer-representation [28] 100.0% [36] 272(0.8%) 272(0.8%) ??? ...ects/racket/contract/private/prop.rkt:282:4 -------------------------------------------------------------------------------- infer-representation [28] 100.0% [37] 248(0.8%) 0(0.0%) return/no-unsupplied ...ct/private/arr-i.rkt:542:0 filter [44] 100.0% -------------------------------------------------------------------------------- one-iter [31] 100.0% [38] 18684(56.6%) 0(0.0%) find-matches .../master/src/core/simplify.rkt:62:0 for-loop [46] 100.0% -------------------------------------------------------------------------------- one-iter [31] 100.0% [39] 6796(20.6%) 0(0.0%) for-loop ...rbie/master/src/core/simplify.rkt:74:2 for-loop [47] 100.0% -------------------------------------------------------------------------------- one-iter [31] 100.0% [40] 1936(5.9%) 0(0.0%) for-loop ...rbie/master/src/core/simplify.rkt:82:2 update-leader! [60] 100.0% -------------------------------------------------------------------------------- mk-ival [32] 100.0% [41] 860(2.6%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [50] 64.4% ??? [48] 35.6% -------------------------------------------------------------------------------- loop [33] 100.0% [42] 632(1.9%) 0(0.0%) for-loop ...ie/master/src/core/extraction.rkt:29:4 for-loop [49] 100.0% -------------------------------------------------------------------------------- rewriter [34] 100.0% [43] 300(0.9%) 0(0.0%) for-loop ...erbie/master/src/core/matcher.rkt:92:4 matcher [51] 100.0% -------------------------------------------------------------------------------- return/no-unsupplied [37] 100.0% [44] 248(0.8%) 248(0.8%) filter ...2/collects/racket/private/list.rkt:256:2 -------------------------------------------------------------------------------- one-iter [31] 100.0% [45] 218(0.7%) 0(0.0%) for-loop ...rbie/master/src/core/simplify.rkt:79:2 set-precompute! [52] 100.0% -------------------------------------------------------------------------------- for-loop [46] 49.2% find-matches [38] 50.8% [46] 18684(56.6%) 592(1.8%) for-loop ...rbie/master/src/core/simplify.rkt:64:8 for-loop [46] 49.2% match-e [53] 48.4% -------------------------------------------------------------------------------- for-loop [39] 100.0% [47] 6796(20.6%) 0(0.0%) for-loop ...rbie/master/src/core/simplify.rkt:77:4 merge-egraph-nodes! [54] 62.6% dedup-children! [55] 17.3% substitute-e [56] 12.4% mk-enode! [72] 7.7% -------------------------------------------------------------------------------- bf [41] 48.3% loop [17] 51.7% [48] 634(1.9%) 634(1.9%) ??? /opt/racket-7.2/collects/ffi/unsafe.rkt:905:4 -------------------------------------------------------------------------------- for-loop [42] 100.0% [49] 632(1.9%) 326(1.0%) for-loop ...e/master/src/core/extraction.rkt:37:16 for-loop [57] 48.4% -------------------------------------------------------------------------------- bf [41] 100.0% [50] 554(1.7%) 554(1.7%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 -------------------------------------------------------------------------------- for-loop [5] 33.3% for-loop [43] 66.7% [51] 300(0.9%) 0(0.0%) matcher ...erbie/master/src/core/matcher.rkt:118:2 ??? [2] 66.7% rewriter [34] 33.3% -------------------------------------------------------------------------------- for-loop [45] 100.0% [52] 218(0.7%) 0(0.0%) set-precompute! ...ster/src/core/simplify.rkt:86:0 for-loop [58] 100.0% -------------------------------------------------------------------------------- for-loop [65] 40.9% for-loop [46] 59.1% [53] 18092(54.8%) 1908(5.8%) match-e .../herbie/master/src/core/ematch.rkt:39:0 for-loop [59] 84.1% variable? [61] 3.5% constant? [100] 2.4% ??? [67] 2.1% -------------------------------------------------------------------------------- for-loop [47] 100.0% [54] 4254(12.9%) 0(0.0%) merge-egraph-nodes! ...r/src/core/egraph.rkt:143:0 update-leader! [60] 80.9% loop! [77] 19.1% -------------------------------------------------------------------------------- for-loop [47] 100.0% [55] 1178(3.6%) 0(0.0%) dedup-children! ...master/src/core/enode.rkt:195:0 loop [62] 74.2% loop [64] 25.8% -------------------------------------------------------------------------------- for-loop [63] 18.1% for-loop [47] 81.9% [56] 842(2.6%) 322(1.0%) substitute-e ...ie/master/src/core/ematch.rkt:64:0 for-loop [63] 52.7% variable? [61] 9.0% -------------------------------------------------------------------------------- for-loop [49] 100.0% [57] 306(0.9%) 306(0.9%) for-loop ...e/master/src/core/extraction.rkt:41:23 -------------------------------------------------------------------------------- set-precompute! [52] 100.0% [58] 218(0.7%) 218(0.7%) for-loop ...rbie/master/src/core/simplify.rkt:88:2 -------------------------------------------------------------------------------- match-e [53] 100.0% [59] 16966(51.4%) 5310(16.1%) for-loop ...erbie/master/src/core/ematch.rkt:52:11 for-loop [65] 59.1% procedure-reduce-keyword-arity-mask [68] 6.2% do-curry [70] 4.0% loop [69] 3.2% make-curried [73] 2.4% foldl [71] 2.3% step [78] 1.3% cp-2 [82] 0.6% foldr [75] 0.6% -------------------------------------------------------------------------------- for-loop [40] 36.0% merge-egraph-nodes! [54] 64.0% [60] 5376(16.3%) 306(0.9%) update-leader! ...master/src/core/egraph.rkt:211:0 for-loop [66] 94.3% -------------------------------------------------------------------------------- substitute-e [56] 11.4% match-e [53] 88.6% [61] 1996(6.0%) 0(0.0%) variable? ...ie/master/src/syntax/syntax.rkt:722:0 value? [104] 55.5% ??? [67] 44.5% -------------------------------------------------------------------------------- dedup-children! [55] 0.6% loop [62] 99.4% [62] 874(2.6%) 874(2.6%) loop ...racket-7.2/collects/racket/list.rkt:434:17 loop [62] 99.4% -------------------------------------------------------------------------------- substitute-e [56] 100.0% [63] 520(1.6%) 0(0.0%) for-loop ...erbie/master/src/core/ematch.rkt:73:24 mk-enode! [72] 56.2% substitute-e [56] 43.8% -------------------------------------------------------------------------------- dedup-children! [55] 14.3% loop [64] 85.7% [64] 304(0.9%) 0(0.0%) loop ...racket-7.2/collects/racket/list.rkt:417:34 loop [64] 85.7% loop [74] 14.3% -------------------------------------------------------------------------------- for-loop [59] 100.0% [65] 14370(43.5%) 500(1.5%) for-loop ...erbie/master/src/core/ematch.rkt:58:37 match-e [53] 93.8% ??? [80] 3.7% -------------------------------------------------------------------------------- update-leader! [60] 100.0% [66] 5070(15.4%) 0(0.0%) for-loop ...erbie/master/src/core/egraph.rkt:216:6 hash-update! [89] 69.6% for-loop [76] 30.4% -------------------------------------------------------------------------------- loop [17] 11.2% finalize-iter! [20] 21.8% variable? [61] 31.8% match-e [53] 35.1% [67] 2794(8.5%) 1870(5.7%) ??? ...contract/private/arrow-val-first.rkt:390:18 ??? [85] 11.2% for-loop [86] 11.1% atab-add-altns [88] 10.7% -------------------------------------------------------------------------------- for-loop [59] 100.0% [68] 1404(4.3%) 1404(4.3%) procedure-reduce-keyword-arity-mask ....rkt:1562:4 -------------------------------------------------------------------------------- loop! [77] 0.6% for-loop [59] 0.8% for-loop [14] 22.9% loop [69] 75.7% [69] 1400(4.2%) 0(0.0%) loop ...-7.2/collects/racket/private/map.rkt:40:19 loop [69] 75.7% infer-double-representation [84] 22.9% step [78] 0.7% loop! [77] 0.6% keyword-apply [83] 0.2% -------------------------------------------------------------------------------- for-loop [59] 100.0% [70] 1366(4.1%) 1366(4.1%) do-curry ...-7.2/collects/racket/function.rkt:71:2 -------------------------------------------------------------------------------- for-loop [4] 27.7% for-loop [59] 72.3% [71] 1084(3.3%) 208(0.6%) foldl ....2/collects/racket/private/list.rkt:229:4 merge2 [79] 53.1% ??? [87] 27.7% -------------------------------------------------------------------------------- for-loop [63] 35.9% for-loop [47] 64.1% [72] 814(2.5%) 296(0.9%) mk-enode! ...erbie/master/src/core/egraph.rkt:97:0 hash-has-key? [81] 63.6% -------------------------------------------------------------------------------- for-loop [59] 100.0% [73] 508(1.5%) 508(1.5%) make-curried ...collects/racket/function.rkt:130:9 -------------------------------------------------------------------------------- loop [64] 100.0% [74] 304(0.9%) 304(0.9%) loop (unknown source) -------------------------------------------------------------------------------- for-loop [59] 100.0% [75] 206(0.6%) 0(0.0%) foldr ....2/collects/racket/private/list.rkt:242:4 cp-2 [82] 100.0% -------------------------------------------------------------------------------- for-loop [66] 100.0% [76] 1540(4.7%) 0(0.0%) for-loop ...erbie/master/src/core/egraph.rkt:217:8 hash-update! [89] 100.0% -------------------------------------------------------------------------------- loop [69] 33.3% merge-egraph-nodes! [54] 66.7% [77] 814(2.5%) 0(0.0%) loop! ...es/herbie/master/src/core/enode.rkt:187:2 custom-set-union [96] 33.4% loop [69] 33.3% update-en-expr [95] 17.7% set [97] 15.6% -------------------------------------------------------------------------------- loop [69] 30.8% for-loop [59] 69.2% [78] 662(2.0%) 662(2.0%) step ...ket-7.2/collects/racket/function.rkt:145:9 -------------------------------------------------------------------------------- foldl [71] 100.0% [79] 576(1.7%) 576(1.7%) merge2 ...s/herbie/master/src/core/ematch.rkt:24:0 -------------------------------------------------------------------------------- for-loop [65] 100.0% [80] 530(1.6%) 0(0.0%) ??? ...lies/herbie/master/src/core/ematch.rkt:43:5 for-loop [91] 100.0% -------------------------------------------------------------------------------- mk-enode! [72] 100.0% [81] 518(1.6%) 518(1.6%) hash-has-key? ...ket/private/more-scheme.rkt:371:2 -------------------------------------------------------------------------------- foldr [75] 50.0% for-loop [59] 50.0% [82] 412(1.2%) 206(0.6%) cp-2 .../racket-7.2/collects/racket/list.rkt:826:2 for-loop [98] 50.0% -------------------------------------------------------------------------------- loop [69] 100.0% [83] 334(1.0%) 334(1.0%) keyword-apply ...lects/racket/private/kw.rkt:423:2 -------------------------------------------------------------------------------- loop [69] 100.0% [84] 320(1.0%) 0(0.0%) infer-double-representation .../src/float.rkt:22:0 for-loop [92] 100.0% -------------------------------------------------------------------------------- ??? [67] 100.0% [85] 314(1.0%) 0(0.0%) ??? ...ects/racket/contract/private/guts.rkt:772:8 bigvalue? [93] 100.0% -------------------------------------------------------------------------------- ??? [67] 100.0% [86] 310(0.9%) 0(0.0%) for-loop ...bie/master/src/core/alt-table.rkt:56:2 atab-add-altn [90] 100.0% -------------------------------------------------------------------------------- foldl [71] 100.0% [87] 300(0.9%) 0(0.0%) ??? ...ies/herbie/master/src/core/matcher.rkt:26:7 core651 [94] 100.0% -------------------------------------------------------------------------------- ??? [67] 100.0% [88] 300(0.9%) 0(0.0%) atab-add-altns ...ster/src/core/alt-table.rkt:50:0 atab-add-altn [90] 100.0% -------------------------------------------------------------------------------- for-loop [76] 30.4% for-loop [66] 69.6% [89] 5070(15.4%) 0(0.0%) hash-update! ...cket/private/more-scheme.rkt:365:4 ??? [99] 100.0% -------------------------------------------------------------------------------- atab-add-altns [88] 49.2% for-loop [86] 50.8% [90] 610(1.8%) 0(0.0%) atab-add-altn ...ster/src/core/alt-table.rkt:203:0 errors [101] 100.0% -------------------------------------------------------------------------------- ??? [80] 100.0% [91] 530(1.6%) 266(0.8%) for-loop ...herbie/master/src/core/ematch.rkt:44:7 constant? [100] 49.8% -------------------------------------------------------------------------------- infer-double-representation [84] 100.0% [92] 320(1.0%) 320(1.0%) for-loop ...tlies/herbie/master/src/float.rkt:17:3 -------------------------------------------------------------------------------- ??? [85] 100.0% [93] 314(1.0%) 314(1.0%) bigvalue? ...rbie/master/src/syntax/types.rkt:19:0 -------------------------------------------------------------------------------- ??? [87] 100.0% [94] 300(0.9%) 300(0.9%) core651 ...acket/contract/private/arr-i.rkt:929:19 -------------------------------------------------------------------------------- loop! [77] 100.0% [95] 288(0.9%) 288(0.9%) update-en-expr ...master/src/core/egraph.rkt:205:0 -------------------------------------------------------------------------------- loop! [77] 100.0% [96] 272(0.8%) 0(0.0%) custom-set-union ...et/private/set-types.rkt:168:0 for-loop [102] 100.0% -------------------------------------------------------------------------------- loop! [77] 100.0% [97] 254(0.8%) 254(0.8%) set ...collects/racket/private/set-types.rkt:981:0 -------------------------------------------------------------------------------- cp-2 [82] 100.0% [98] 206(0.6%) 206(0.6%) for-loop ...ket-7.2/collects/racket/list.rkt:827:4 -------------------------------------------------------------------------------- hash-update! [89] 100.0% [99] 5070(15.4%) 0(0.0%) ??? ...es/herbie/master/src/core/egraph.rkt:219:24 for-loop [103] 100.0% -------------------------------------------------------------------------------- for-loop [91] 27.2% match-e [53] 72.8% [100] 970(2.9%) 200(0.6%) constant? ...ie/master/src/syntax/syntax.rkt:719:0 value? [104] 79.4% -------------------------------------------------------------------------------- ??? [6] 30.8% atab-add-altn [90] 69.2% [101] 882(2.7%) 0(0.0%) errors ...tlies/herbie/master/src/points.rkt:205:0 for-loop [105] 100.0% -------------------------------------------------------------------------------- custom-set-union [96] 100.0% [102] 272(0.8%) 272(0.8%) for-loop ...cts/racket/private/set-types.rkt:152:2 -------------------------------------------------------------------------------- ??? [99] 100.0% [103] 5070(15.4%) 5070(15.4%) for-loop ...rbie/master/src/core/egraph.rkt:220:26 -------------------------------------------------------------------------------- constant? [100] 41.0% variable? [61] 59.0% [104] 1878(5.7%) 1878(5.7%) value? .../herbie/master/src/syntax/types.rkt:18:0 -------------------------------------------------------------------------------- errors [101] 100.0% [105] 882(2.7%) 0(0.0%) for-loop ...ies/herbie/master/src/points.rkt:207:2 ??? [6] 100.0% --------------------------------------------------------------------------------