Profiling results ----------------- Total cpu time observed: 62848ms (out of 63640ms) Number of samples taken: 193 (once every 326ms) ===================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ===================================================================== ??? [35] 100.0% [1] 62848(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [3] 100.0% --------------------------------------------------------------------- ??? [35] 100.0% [2] 54968(87.5%) 0(0.0%) run-improve40 ...ie/develop/src/mainloop.rkt:340:0 simplify! [4] 98.7% prepare-points-intervals [5] 0.7% for-loop [6] 0.6% --------------------------------------------------------------------- profile-thunk16 [1] 100.0% [3] 62848(100.0%) 0(0.0%) run ...et-7.0/share/pkgs/profile-lib/main.rkt:39:2 ??? [35] 100.0% --------------------------------------------------------------------- run-improve40 [2] 100.0% [4] 54272(86.4%) 358(0.6%) simplify! ...herbie/develop/src/mainloop.rkt:217:0 ??? [7] 99.3% --------------------------------------------------------------------- run-improve40 [2] 4.9% ??? [35] 95.1% [5] 7354(11.7%) 0(0.0%) prepare-points-intervals ...p/src/points.rkt:122:0 loop [8] 95.1% eval-prog [9] 4.9% --------------------------------------------------------------------- run-improve40 [2] 100.0% [6] 336(0.5%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:355:4 run-iter! [10] 100.0% --------------------------------------------------------------------- simplify! [4] 100.0% [7] 53914(85.8%) 0(0.0%) ??? ...s/herbie/develop/src/core/simplify.rkt:29:0 loop [11] 96.7% loop [13] 3.3% --------------------------------------------------------------------- prepare-points-intervals [5]100.0% [8] 6994(11.1%) 334(0.5%) loop ...htlies/herbie/develop/src/points.rkt:138:4 loop [12] 81.4% loop [67] 9.5% ??? [82] 4.3% --------------------------------------------------------------------- prepare-points-intervals [5]100.0% [9] 360(0.6%) 0(0.0%) eval-prog ...herbie/develop/src/programs.rkt:130:0 loop [14] 100.0% --------------------------------------------------------------------- for-loop [6] 100.0% [10] 336(0.5%) 0(0.0%) run-iter! ...herbie/develop/src/mainloop.rkt:321:0 localize! [15] 100.0% --------------------------------------------------------------------- ??? [7] 100.0% [11] 52110(82.9%) 0(0.0%) loop .../herbie/develop/src/core/simplify.rkt:44:2 one-iter [16] 98.8% loop [18] 1.2% --------------------------------------------------------------------- loop [8] 100.0% [12] 5696(9.1%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:103:2 ??? [79] 66.7% ??? [82] 22.4% ??? [55] 5.5% hash-update! [70] 5.3% --------------------------------------------------------------------- ??? [7] 100.0% [13] 1804(2.9%) 0(0.0%) loop ...herbie/develop/src/core/simplify.rkt:132:2 for-loop [17] 100.0% --------------------------------------------------------------------- loop [14] 33.3% expand-capturing-lifts [19] 33.3% eval-prog [9] 33.3% [14] 360(0.6%) 0(0.0%) loop (unknown source) expand-capturing-lifts [19] 33.3% loop [14] 33.3% for-loop [20] 33.3% --------------------------------------------------------------------- run-iter! [10] 100.0% [15] 336(0.5%) 0(0.0%) localize! ...herbie/develop/src/mainloop.rkt:120:0 localize-error [21] 100.0% --------------------------------------------------------------------- loop [11] 100.0% [16] 51482(81.9%) 0(0.0%) one-iter ...ie/develop/src/core/simplify.rkt:100:0 for-loop [22] 96.5% find-matches [23] 3.5% --------------------------------------------------------------------- loop [13] 100.0% [17] 1804(2.9%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:138:4 for-loop [31] 33.7% loop [25] 33.3% expression-cost [33] 16.6% for-loop [28] 16.4% --------------------------------------------------------------------- loop [11] 100.0% [18] 628(1.0%) 0(0.0%) loop ...-7.0/collects/racket/private/map.rkt:74:19 set-precompute! [24] 100.0% --------------------------------------------------------------------- loop [14] 100.0% [19] 360(0.6%) 0(0.0%) expand-capturing-lifts (unknown source) loop [14] 100.0% --------------------------------------------------------------------- run-module-instance!125 [27] 50.0% loop [14] 50.0% [20] 360(0.6%) 0(0.0%) for-loop (unknown source) run-module-instance!125 [27] 50.0% namespace->name [26] 50.0% --------------------------------------------------------------------- localize! [15] 100.0% [21] 336(0.5%) 0(0.0%) localize-error ...velop/src/core/localize.rkt:52:0 hash-ref! [44] 100.0% --------------------------------------------------------------------- one-iter [16] 100.0% [22] 49662(79.0%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:101:2 apply-match [29] 100.0% --------------------------------------------------------------------- one-iter [16] 100.0% [23] 1820(2.9%) 0(0.0%) find-matches ...develop/src/core/simplify.rkt:64:0 for-loop [30] 100.0% --------------------------------------------------------------------- loop [18] 100.0% [24] 628(1.0%) 0(0.0%) set-precompute! ...lop/src/core/simplify.rkt:105:0 for-loop [32] 100.0% --------------------------------------------------------------------- for-loop [17] 100.0% [25] 600(1.0%) 0(0.0%) loop .../racket-7.0/collects/racket/list.rkt:751:4 expression-cost [33] 50.0% for-loop [31] 50.0% --------------------------------------------------------------------- for-loop [20] 100.0% [26] 360(0.6%) 360(0.6%) namespace->name (unknown source) --------------------------------------------------------------------- for-loop [20] 100.0% [27] 360(0.6%) 0(0.0%) run-module-instance!125 (unknown source) for-loop [20] 100.0% --------------------------------------------------------------------- for-loop [17] 100.0% [28] 296(0.5%) 0(0.0%) for-loop ...e/develop/src/core/simplify.rkt:142:16 for-loop [34] 100.0% --------------------------------------------------------------------- for-loop [22] 100.0% [29] 49662(79.0%) 0(0.0%) apply-match .../develop/src/core/simplify.rkt:73:0 for-loop [36] 67.8% update-leader! [52] 21.3% match-e [37] 8.5% set [69] 1.8% reduce-to-single! [40] 0.6% --------------------------------------------------------------------- for-loop [30] 50.0% find-matches [23] 50.0% [30] 1820(2.9%) 298(0.5%) for-loop ...bie/develop/src/core/simplify.rkt:66:8 for-loop [30] 50.0% match-e [37] 41.8% --------------------------------------------------------------------- loop [25] 33.0% for-loop [17] 67.0% [31] 908(1.4%) 308(0.5%) for-loop .../herbie/develop/src/programs.rkt:205:2 table-ref [38] 66.1% --------------------------------------------------------------------- set-precompute! [24] 100.0% [32] 628(1.0%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:108:2 ??? [35] 52.5% setfindf [41] 47.5% --------------------------------------------------------------------- loop [25] 50.0% for-loop [17] 50.0% [33] 600(1.0%) 0(0.0%) expression-cost .../develop/src/programs.rkt:204:0 compile [39] 100.0% --------------------------------------------------------------------- for-loop [28] 100.0% [34] 296(0.5%) 296(0.5%) for-loop ...e/develop/src/core/simplify.rkt:146:23 --------------------------------------------------------------------- for-loop [32] 0.2% run [3] 49.9% [35] 62848(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 49.9% run-improve40 [2] 43.6% prepare-points-intervals [5] 5.6% errors [48] 0.2% eval-errors [50] 0.2% oracle-error [51] 0.2% merge-egraph-nodes! [42] 0.2% --------------------------------------------------------------------- apply-match [29] 100.0% [36] 33670(53.6%) 298(0.5%) for-loop ...bie/develop/src/core/simplify.rkt:87:2 merge-egraph-nodes! [42] 78.4% loop! [64] 18.9% substitute-e [45] 1.9% --------------------------------------------------------------------- for-loop [30] 20.4% apply-match [29] 36.8% for-loop [54] 42.9% [37] 5744(9.1%) 1222(1.9%) match-e ...herbie/develop/src/core/ematch.rkt:39:0 for-loop [43] 77.0% ??? [55] 3.5% variable? [46] 3.4% --------------------------------------------------------------------- for-loop [31] 100.0% [38] 600(1.0%) 600(1.0%) table-ref ...es/herbie/develop/src/common.rkt:42:0 --------------------------------------------------------------------- expression-cost [33] 100.0% [39] 600(1.0%) 0(0.0%) compile ...s/herbie/develop/src/programs.rkt:181:0 hash-ref! [44] 100.0% --------------------------------------------------------------------- apply-match [29] 100.0% [40] 300(0.5%) 0(0.0%) reduce-to-single! ...lop/src/core/egraph.rkt:319:0 custom-in-set [47] 100.0% --------------------------------------------------------------------- for-loop [32] 100.0% [41] 298(0.5%) 0(0.0%) setfindf ...es/herbie/develop/src/common.rkt:139:0 for-loop [49] 100.0% --------------------------------------------------------------------- ??? [35] 1.2% merge-egraph-nodes! [42] 3.4% for-loop [53] 11.4% for-loop [36] 84.0% [42] 26712(42.5%) 0(0.0%) merge-egraph-nodes! ...p/src/core/egraph.rkt:144:0 update-leader! [52] 72.9% for-loop [53] 14.9% loop! [64] 8.8% merge-egraph-nodes! [42] 3.4% --------------------------------------------------------------------- match-e [37] 100.0% [43] 5122(8.1%) 3020(4.8%) for-loop ...rbie/develop/src/core/ematch.rkt:52:11 for-loop [54] 61.7% loop [58] 3.0% --------------------------------------------------------------------- compile [39] 26.7% localize-error [21] 35.9% ??? [56] 37.4% [44] 936(1.5%) 300(0.5%) hash-ref! .../racket/private/more-scheme.rkt:376:2 ??? [56] 53.4% parse-loop15 [57] 35.9% --------------------------------------------------------------------- for-loop [59] 23.9% for-loop [36] 76.1% [45] 636(1.0%) 636(1.0%) substitute-e ...e/develop/src/core/ematch.rkt:64:0 for-loop [59] 23.9% --------------------------------------------------------------------- match-e [37] 100.0% [46] 594(0.9%) 0(0.0%) variable? .../develop/src/syntax/syntax.rkt:1159:0 ??? [55] 100.0% --------------------------------------------------------------------- reduce-to-single! [40] 100.0% [47] 300(0.5%) 300(0.5%) custom-in-set ...acket/private/set-types.rkt:577:0 --------------------------------------------------------------------- ??? [35] 100.0% [48] 300(0.5%) 0(0.0%) errors ...lies/herbie/develop/src/points.rkt:223:0 for-loop [60] 100.0% --------------------------------------------------------------------- setfindf [41] 100.0% [49] 298(0.5%) 298(0.5%) for-loop ...es/herbie/develop/src/common.rkt:140:2 --------------------------------------------------------------------- ??? [35] 100.0% [50] 298(0.5%) 0(0.0%) eval-errors ...herbie/develop/src/points.rkt:198:0 for-loop [61] 100.0% --------------------------------------------------------------------- ??? [35] 100.0% [51] 288(0.5%) 0(0.0%) oracle-error ...erbie/develop/src/points.rkt:207:0 for-loop [62] 100.0% --------------------------------------------------------------------- apply-match [29] 32.3% merge-egraph-nodes! [42] 67.7% [52] 32732(52.1%) 0(0.0%) update-leader! ...evelop/src/core/egraph.rkt:212:0 for-loop [63] 100.0% --------------------------------------------------------------------- merge-egraph-nodes! [42] 100.0% [53] 6952(11.1%) 0(0.0%) for-loop ...rbie/develop/src/core/egraph.rkt:198:4 merge-egraph-nodes! [42] 86.2% loop! [64] 13.8% --------------------------------------------------------------------- for-loop [43] 100.0% [54] 4526(7.2%) 0(0.0%) for-loop ...rbie/develop/src/core/ematch.rkt:58:37 match-e [37] 100.0% --------------------------------------------------------------------- loop [12] 21.0% variable? [46] 39.4% match-e [37] 39.7% [55] 1508(2.4%) 1508(2.4%) ??? ...contract/private/arrow-val-first.rkt:388:18 --------------------------------------------------------------------- hash-ref! [44] 100.0% [56] 600(1.0%) 300(0.5%) ??? ...tlies/herbie/develop/src/programs.rkt:189:5 hash-ref! [44] 75.0% --------------------------------------------------------------------- hash-ref! [44] 100.0% [57] 336(0.5%) 0(0.0%) parse-loop15 ...s/racket/match/compiler.rkt:418:15 loop [67] 100.0% --------------------------------------------------------------------- for-loop [43] 100.0% [58] 310(0.5%) 310(0.5%) loop ...cket-7.0/collects/racket/function.rkt:53:6 --------------------------------------------------------------------- substitute-e [45] 100.0% [59] 304(0.5%) 0(0.0%) for-loop ...rbie/develop/src/core/ematch.rkt:73:24 substitute-e [45] 100.0% --------------------------------------------------------------------- errors [48] 100.0% [60] 300(0.5%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:225:2 ??? [79] 100.0% --------------------------------------------------------------------- eval-errors [50] 100.0% [61] 298(0.5%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:200:2 ulp-difference [65] 100.0% --------------------------------------------------------------------- oracle-error [51] 100.0% [62] 288(0.5%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:208:2 map [72] 100.0% --------------------------------------------------------------------- update-leader! [52] 100.0% [63] 32732(52.1%) 298(0.5%) for-loop ...rbie/develop/src/core/egraph.rkt:217:6 hash-update! [70] 58.1% for-loop [66] 41.0% --------------------------------------------------------------------- for-loop [53] 8.9% merge-egraph-nodes! [42] 21.1% loop [67] 29.1% for-loop [36] 40.9% [64] 10908(17.4%) 1940(3.1%) loop! ...s/herbie/develop/src/core/enode.rkt:190:2 custom-set-union [68] 39.5% loop [67] 32.2% set [69] 15.2% update-en-expr [80] 4.3% --------------------------------------------------------------------- for-loop [61] 100.0% [65] 298(0.5%) 0(0.0%) ulp-difference ...erbie/develop/src/float.rkt:14:0 return/no-unsupplied [89] 100.0% --------------------------------------------------------------------- for-loop [63] 100.0% [66] 13410(21.3%) 300(0.5%) for-loop ...rbie/develop/src/core/egraph.rkt:218:8 hash-update! [70] 97.8% --------------------------------------------------------------------- parse-loop15 [57] 0.1% loop [8] 8.9% loop! [64] 18.6% loop [67] 72.4% [67] 7454(11.9%) 334(0.5%) loop ...-7.0/collects/racket/private/map.rkt:40:19 loop [67] 72.4% loop! [64] 18.4% ??? [82] 8.9% map [72] 0.1% --------------------------------------------------------------------- loop! [64] 100.0% [68] 4454(7.1%) 628(1.0%) custom-set-union ...et/private/set-types.rkt:168:0 for-loop [71] 78.7% for-loop [74] 7.2% --------------------------------------------------------------------- apply-match [29] 21.6% loop! [64] 78.4% [69] 4144(6.6%) 3546(5.6%) set ...collects/racket/private/set-types.rkt:981:0 for-loop [73] 14.4% --------------------------------------------------------------------- loop [12] 0.9% for-loop [66] 40.4% for-loop [63] 58.7% [70] 32434(51.6%) 300(0.5%) hash-update! ...cket/private/more-scheme.rkt:365:4 ??? [75] 99.1% --------------------------------------------------------------------- custom-set-union [68] 100.0% [71] 3504(5.6%) 1306(2.1%) for-loop ...cts/racket/private/set-types.rkt:174:3 for-loop [76] 62.7% --------------------------------------------------------------------- for-loop [62] 46.2% loop [67] 53.8% [72] 624(1.0%) 0(0.0%) map ...t-7.0/collects/racket/private/map.rkt:35:13 ??? [82] 53.8% ??? [77] 46.2% --------------------------------------------------------------------- set [69] 100.0% [73] 598(1.0%) 598(1.0%) for-loop ...cts/racket/private/set-types.rkt:937:4 --------------------------------------------------------------------- custom-set-union [68] 100.0% [74] 322(0.5%) 322(0.5%) for-loop ...cts/racket/private/set-types.rkt:152:2 --------------------------------------------------------------------- hash-update! [70] 100.0% [75] 32134(51.1%) 0(0.0%) ??? ...s/herbie/develop/src/core/egraph.rkt:220:24 for-loop [78] 100.0% --------------------------------------------------------------------- for-loop [71] 100.0% [76] 2198(3.5%) 2198(3.5%) for-loop ...cts/racket/private/set-types.rkt:178:5 --------------------------------------------------------------------- map [72] 100.0% [77] 288(0.5%) 0(0.0%) ??? ...htlies/herbie/develop/src/points.rkt:209:26 ??? [79] 100.0% --------------------------------------------------------------------- ??? [75] 100.0% [78] 32134(51.1%) 28692(45.7%) for-loop ...bie/develop/src/core/egraph.rkt:221:26 update-en-expr [80] 10.7% --------------------------------------------------------------------- ??? [77] 6.6% for-loop [60] 6.8% loop [12] 86.6% [79] 4390(7.0%) 0(0.0%) ??? ...tlies/herbie/develop/src/programs.rkt:146:2 ??? [81] 65.8% ??? [83] 20.8% ??? [82] 13.4% --------------------------------------------------------------------- loop! [64] 21.3% for-loop [78] 78.7% [80] 4374(7.0%) 4374(7.0%) update-en-expr ...evelop/src/core/egraph.rkt:206:0 --------------------------------------------------------------------- ??? [79] 100.0% [81] 2890(4.6%) 0(0.0%) ??? ...t-7.0/collects/racket/private/kw.rkt:592:14 ??? [82] 77.1% ??? [83] 22.9% --------------------------------------------------------------------- loop [8] 5.6% map [72] 6.2% ??? [79] 10.9% loop [67] 12.3% loop [12] 23.7% ??? [81] 41.3% [82] 5394(8.6%) 978(1.6%) ??? ...tract/private/arrow-higher-order.rkt:357:33 ->flonum [84] 34.9% ival-mult [85] 34.6% sample-multi-bounded [87] 12.3% --------------------------------------------------------------------- ??? [81] 42.1% ??? [79] 57.9% [83] 1574(2.5%) 350(0.6%) ??? ...tract/private/arrow-higher-order.rkt:361:33 mk-ival [86] 57.9% ival-sub [88] 19.8% --------------------------------------------------------------------- ??? [82] 100.0% [84] 1884(3.0%) 288(0.5%) ->flonum ...s/herbie/develop/src/programs.rkt:38:0 return/no-unsupplied [89] 52.1% ??? [96] 16.7% ??? [94] 15.9% --------------------------------------------------------------------- ??? [82] 100.0% [85] 1868(3.0%) 0(0.0%) ival-mult ...rbie/develop/src/biginterval.rkt:95:0 bf* [90] 51.2% classify-ival [91] 48.8% --------------------------------------------------------------------- ??? [83] 100.0% [86] 912(1.5%) 294(0.5%) mk-ival ...herbie/develop/src/biginterval.rkt:53:0 bf [92] 67.8% --------------------------------------------------------------------- ??? [82] 100.0% [87] 664(1.1%) 356(0.6%) sample-multi-bounded ...evelop/src/points.rkt:15:0 loop [93] 46.4% --------------------------------------------------------------------- ??? [83] 100.0% [88] 312(0.5%) 312(0.5%) ival-sub ...erbie/develop/src/biginterval.rkt:83:0 --------------------------------------------------------------------- ulp-difference [65] 23.3% ->flonum [84] 76.7% [89] 1280(2.0%) 934(1.5%) return/no-unsupplied ...ct/private/arr-i.rkt:534:0 filter [97] 27.0% --------------------------------------------------------------------- ival-mult [85] 100.0% [90] 956(1.5%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [95] 100.0% --------------------------------------------------------------------- ival-mult [85] 100.0% [91] 912(1.5%) 614(1.0%) classify-ival ...develop/src/biginterval.rkt:300:0 fold-binary-pred [99] 32.7% --------------------------------------------------------------------- mk-ival [86] 100.0% [92] 618(1.0%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 ??? [96] 100.0% --------------------------------------------------------------------- sample-multi-bounded [87] 100.0% [93] 308(0.5%) 0(0.0%) loop ...ghtlies/herbie/develop/src/points.rkt:44:6 random-exp [98] 100.0% --------------------------------------------------------------------- ->flonum [84] 100.0% [94] 300(0.5%) 300(0.5%) ??? ...s/racket/contract/private/arr-i.rkt:1252:29 --------------------------------------------------------------------- bf* [90] 100.0% [95] 956(1.5%) 0(0.0%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [100] 100.0% --------------------------------------------------------------------- ->flonum [84] 33.7% bf [92] 66.3% [96] 932(1.5%) 932(1.5%) ??? /opt/racket-7.0/collects/ffi/unsafe.rkt:898:4 --------------------------------------------------------------------- return/no-unsupplied [89] 100.0% [97] 346(0.6%) 346(0.6%) filter ...0/collects/racket/private/list.rkt:256:2 --------------------------------------------------------------------- loop [93] 100.0% [98] 308(0.5%) 308(0.5%) random-exp .../herbie/develop/src/common.rkt:173:0 --------------------------------------------------------------------- classify-ival [91] 100.0% [99] 298(0.5%) 298(0.5%) fold-binary-pred ...gfloat/bigfloat-mpfr.rkt:117:0 --------------------------------------------------------------------- ??? [95] 100.0% [100] 956(1.5%) 298(0.5%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [101] 68.8% --------------------------------------------------------------------- bfmul [100] 100.0% [101] 658(1.0%) 658(1.0%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ---------------------------------------------------------------------