Profiling results ----------------- Total cpu time observed: 142552ms (out of 144364ms) Number of samples taken: 379 (once every 376ms) (Hiding functions with self<1.0% and local<2.0%: 3 of 103 hidden) =========================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee =========================================================================== run [11] 50.0% [1] 142552(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [6] 50.0% loop [17] 47.2% run-improve39 [7] 2.7% get-final-combination [10] 0.1% --------------------------------------------------------------------------- hash-ref! [67] 100.0% [2] 3496(2.5%) 0(0.0%) ??? ...tlies/herbie/develop/src/programs.rkt:153:5 loop [63] 100.0% --------------------------------------------------------------------------- parse-loop14 [76] 2.1% loop [3] 97.9% [3] 334(0.2%) 0(0.0%) loop ...cket/collects/racket/private/map.rkt:54:19 loop [3] 97.9% ??? [68] 2.1% --------------------------------------------------------------------------- for-loop [90] 100.0% [4] 322(0.2%) 0(0.0%) syntax-e (unknown source) syntax-e/no-taint [8] 100.0% --------------------------------------------------------------------------- loop [63] 100.0% [5] 310(0.2%) 0(0.0%) set-precompute! ...lop/src/core/simplify.rkt:140:0 for-loop [9] 100.0% --------------------------------------------------------------------------- ??? [1] 100.0% [6] 142552(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [11] 100.0% --------------------------------------------------------------------------- ??? [1] 100.0% [7] 7790(5.5%) 0(0.0%) run-improve39 ...ie/develop/src/mainloop.rkt:286:0 setup-prog!32 [12] 67.0% for-loop [13] 28.8% for-loop [14] 4.2% --------------------------------------------------------------------------- syntax-e [4] 100.0% [8] 322(0.2%) 0(0.0%) syntax-e/no-taint (unknown source) loop [53] 100.0% --------------------------------------------------------------------------- set-precompute! [5] 100.0% [9] 310(0.2%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:142:2 eval-const-expr [15] 100.0% --------------------------------------------------------------------------- ??? [1] 100.0% [10] 302(0.2%) 0(0.0%) get-final-combination ...op/src/mainloop.rkt:309:0 argmins [16] 100.0% --------------------------------------------------------------------------- profile-thunk16 [6] 100.0% [11] 142552(100.0%) 0(0.0%) run ...racket/share/pkgs/profile-lib/main.rkt:39:2 ??? [1] 100.0% --------------------------------------------------------------------------- run-improve39 [7] 100.0% [12] 5216(3.7%) 0(0.0%) setup-prog!32 ...bie/develop/src/mainloop.rkt:63:0 loop [17] 100.0% --------------------------------------------------------------------------- run-improve39 [7] 100.0% [13] 2244(1.6%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:303:8 run-iter! [18] 100.0% --------------------------------------------------------------------------- run-improve39 [7] 100.0% [14] 330(0.2%) 0(0.0%) for-loop ...herbie/develop/src/mainloop.rkt:298:29 ??? [19] 100.0% --------------------------------------------------------------------------- for-loop [9] 100.0% [15] 310(0.2%) 0(0.0%) eval-const-expr .../develop/src/programs.rkt:132:0 eval-prog [48] 100.0% --------------------------------------------------------------------------- get-final-combination [10] 100.0% [16] 302(0.2%) 0(0.0%) argmins ...ies/herbie/develop/src/common.rkt:142:0 ??? [20] 100.0% --------------------------------------------------------------------------- setup-prog!32 [12] 3.7% ??? [1] 96.3% [17] 139676(98.0%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:166:2 loop [30] 51.9% loop [21] 32.6% make-exacts* [31] 12.5% make-exacts [22] 2.6% for-loop [24] 0.5% --------------------------------------------------------------------------- for-loop [13] 100.0% [18] 2244(1.6%) 0(0.0%) run-iter! ...herbie/develop/src/mainloop.rkt:267:0 localize-error [23] 44.7% simplify! [25] 27.1% finalize-iter! [27] 14.3% gen-rewrites! [29] 13.8% --------------------------------------------------------------------------- ??? [68] 48.4% for-loop [14] 51.6% [19] 640(0.4%) 0(0.0%) ??? ...s/herbie/develop/src/core/simplify.rkt:31:0 loop [26] 51.6% iterate-egraph!7 [28] 48.4% --------------------------------------------------------------------------- argmins [16] 100.0% [20] 302(0.2%) 0(0.0%) ??? ...lies/herbie/develop/src/mainloop.rkt:110:19 errors [45] 100.0% --------------------------------------------------------------------------- loop [17] 100.0% [21] 45538(31.9%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:124:2 loop [30] 61.8% make-exacts* [31] 36.4% eval-prog [48] 1.8% --------------------------------------------------------------------------- loop [17] 100.0% [22] 3632(2.5%) 0(0.0%) make-exacts ...herbie/develop/src/points.rkt:122:0 make-exacts* [31] 65.7% loop [30] 34.3% --------------------------------------------------------------------------- run-iter! [18] 100.0% [23] 1004(0.7%) 0(0.0%) localize-error ...velop/src/core/localize.rkt:51:0 hash-ref! [67] 100.0% --------------------------------------------------------------------------- loop [17] 100.0% [24] 642(0.5%) 0(0.0%) for-loop ...s/herbie/develop/src/points.rkt:181:19 for-loop [32] 100.0% --------------------------------------------------------------------------- run-iter! [18] 100.0% [25] 608(0.4%) 0(0.0%) simplify! ...herbie/develop/src/mainloop.rkt:181:0 for-loop [33] 100.0% --------------------------------------------------------------------------- ??? [19] 100.0% [26] 330(0.2%) 330(0.2%) loop ...herbie/develop/src/core/simplify.rkt:184:2 --------------------------------------------------------------------------- run-iter! [18] 100.0% [27] 322(0.2%) 0(0.0%) finalize-iter! ...e/develop/src/mainloop.rkt:221:0 ??? [34] 100.0% --------------------------------------------------------------------------- ??? [19] 100.0% [28] 310(0.2%) 0(0.0%) iterate-egraph!7 ...lop/src/core/simplify.rkt:55:0 map-enodes [35] 100.0% --------------------------------------------------------------------------- run-iter! [18] 100.0% [29] 310(0.2%) 0(0.0%) gen-rewrites! ...ie/develop/src/mainloop.rkt:164:0 for-loop [36] 100.0% --------------------------------------------------------------------------- make-exacts [22] 1.2% loop [21] 27.6% loop [17] 71.1% [30] 101810(71.4%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:107:4 loop [63] 99.4% ??? [66] 0.6% --------------------------------------------------------------------------- make-exacts [22] 6.6% loop [21] 45.6% loop [17] 47.9% [31] 36418(25.5%) 0(0.0%) make-exacts* ...erbie/develop/src/points.rkt:104:0 loop [63] 87.0% eval-prog [48] 13.0% --------------------------------------------------------------------------- for-loop [24] 100.0% [32] 642(0.5%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:158:4 ??? [68] 100.0% --------------------------------------------------------------------------- simplify! [25] 100.0% [33] 608(0.4%) 298(0.2%) for-loop .../herbie/develop/src/mainloop.rkt:185:6 for-loop [38] 51.0% --------------------------------------------------------------------------- finalize-iter! [27] 100.0% [34] 322(0.2%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:388:18 for-loop [37] 100.0% --------------------------------------------------------------------------- iterate-egraph!7 [28] 100.0% [35] 310(0.2%) 0(0.0%) map-enodes ...ie/develop/src/core/egraph.rkt:140:0 loop [63] 100.0% --------------------------------------------------------------------------- gen-rewrites! [29] 100.0% [36] 310(0.2%) 0(0.0%) for-loop ...herbie/develop/src/mainloop.rkt:169:11 ??? [39] 100.0% --------------------------------------------------------------------------- ??? [34] 100.0% [37] 322(0.2%) 0(0.0%) for-loop ...ie/develop/src/core/alt-table.rkt:51:2 atab-add-altn [40] 100.0% --------------------------------------------------------------------------- for-loop [33] 100.0% [38] 310(0.2%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:209:8 ??? [68] 100.0% --------------------------------------------------------------------------- for-loop [36] 100.0% [39] 310(0.2%) 0(0.0%) ??? ...ies/herbie/develop/src/alternative.rkt:60:0 rewriter [41] 100.0% --------------------------------------------------------------------------- for-loop [37] 100.0% [40] 322(0.2%) 0(0.0%) atab-add-altn ...elop/src/core/alt-table.rkt:210:0 override-at-pnts [42] 100.0% --------------------------------------------------------------------------- matcher [47] 50.0% ??? [39] 50.0% [41] 310(0.2%) 0(0.0%) rewriter ...bie/develop/src/core/matcher.rkt:131:2 for-loop [44] 50.0% *rules* [43] 50.0% --------------------------------------------------------------------------- atab-add-altn [40] 100.0% [42] 322(0.2%) 0(0.0%) override-at-pnts ...p/src/core/alt-table.rkt:145:0 errors [45] 100.0% --------------------------------------------------------------------------- rewriter [41] 100.0% [43] 310(0.2%) 0(0.0%) *rules* ...rbie/develop/src/syntax/rules.rkt:643:0 for-loop [46] 100.0% --------------------------------------------------------------------------- rewriter [41] 100.0% [44] 310(0.2%) 0(0.0%) for-loop ...ie/develop/src/core/matcher.rkt:134:10 matcher [47] 100.0% --------------------------------------------------------------------------- ??? [20] 48.4% override-at-pnts [42] 51.6% [45] 624(0.4%) 0(0.0%) errors ...lies/herbie/develop/src/points.rkt:207:0 eval-prog [48] 51.6% for-loop [51] 48.4% --------------------------------------------------------------------------- *rules* [43] 100.0% [46] 310(0.2%) 0(0.0%) for-loop ...ies/herbie/develop/src/common.rkt:92:9 ormap [49] 100.0% --------------------------------------------------------------------------- for-loop [50] 50.0% for-loop [44] 50.0% [47] 310(0.2%) 0(0.0%) matcher ...rbie/develop/src/core/matcher.rkt:168:2 for-loop [50] 50.0% rewriter [41] 50.0% --------------------------------------------------------------------------- eval-const-expr [15] 5.0% errors [45] 5.2% loop [21] 13.1% make-exacts* [31] 76.7% [48] 6172(4.3%) 0(0.0%) eval-prog ...herbie/develop/src/programs.rkt:109:0 hash-ref! [67] 62.1% f8 [52] 11.2% loop [53] 11.1% temp68_2 [54] 10.2% ->bf [55] 5.4% --------------------------------------------------------------------------- for-loop [46] 100.0% [49] 310(0.2%) 0(0.0%) ormap ...et/collects/racket/private/map.rkt:141:13 flag-set? [56] 100.0% --------------------------------------------------------------------------- matcher [47] 100.0% [50] 310(0.2%) 0(0.0%) for-loop ...ie/develop/src/core/matcher.rkt:184:21 matcher [47] 100.0% --------------------------------------------------------------------------- errors [45] 100.0% [51] 302(0.2%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:210:4 ??? [66] 100.0% --------------------------------------------------------------------------- eval-prog [48] 12.1% f8 [52] 28.8% hash-ref! [67] 59.1% [52] 1698(1.2%) 0(0.0%) f8 ...et/collects/racket/match/compiler.rkt:507:40 map [57] 59.1% f8 [52] 28.8% bf [72] 7.5% table-ref [61] 4.6% --------------------------------------------------------------------------- loop [53] 11.8% expand-capturing-lifts [75] 11.8% syntax-e/no-taint [8] 32.0% eval-prog [48] 44.4% [53] 1006(0.7%) 0(0.0%) loop (unknown source) expand-capturing-lifts [75] 44.4% gf [60] 32.0% loop [53] 11.8% for-loop [90] 11.8% --------------------------------------------------------------------------- eval-prog [48] 100.0% [54] 632(0.4%) 0(0.0%) temp68_2 (unknown source) compile16 [58] 100.0% --------------------------------------------------------------------------- eval-prog [48] 100.0% [55] 332(0.2%) 0(0.0%) ->bf ...tlies/herbie/develop/src/programs.rkt:52:0 ...velop/src/common.rkt:48:2 [59]100.0% --------------------------------------------------------------------------- ormap [49] 100.0% [56] 310(0.2%) 0(0.0%) flag-set? ...es/herbie/develop/src/config.rkt:31:0 return/no-unsupplied [62] 100.0% --------------------------------------------------------------------------- f8 [52] 100.0% [57] 1004(0.7%) 0(0.0%) map ...acket/collects/racket/private/map.rkt:35:13 hash-ref! [67] 66.6% loop [63] 16.7% ??? [78] 16.6% --------------------------------------------------------------------------- temp68_2 [54] 100.0% [58] 632(0.4%) 0(0.0%) compile16 (unknown source) temp74_0 [64] 100.0% --------------------------------------------------------------------------- ->bf [55] 100.0% [59] 332(0.2%) 0(0.0%) ...velop/src/common.rkt:48:2 ...e/arr-i.rkt:918:19 ??? [65] 100.0% --------------------------------------------------------------------------- loop [53] 100.0% [60] 322(0.2%) 322(0.2%) gf (unknown source) --------------------------------------------------------------------------- f8 [52] 100.0% [61] 314(0.2%) 314(0.2%) table-ref ...es/herbie/develop/src/common.rkt:47:0 --------------------------------------------------------------------------- flag-set? [56] 100.0% [62] 310(0.2%) 310(0.2%) return/no-unsupplied ...ct/private/arr-i.rkt:534:0 --------------------------------------------------------------------------- map [57] 0.0% map-enodes [35] 0.1% parse-loop14 [76] 0.5% make-exacts* [31] 0.7% loop [30] 1.2% ??? [2] 2.5% loop [63] 94.9% [63] 137660(96.6%) 0(0.0%) loop ...cket/collects/racket/private/map.rkt:40:19 loop [63] 94.9% hash-ref! [67] 3.1% ??? [66] 1.9% set-precompute! [5] 0.1% ??? [68] 0.0% ??? [78] 0.0% --------------------------------------------------------------------------- compile16 [58] 100.0% [64] 632(0.4%) 0(0.0%) temp74_0 (unknown source) expand-single [69] 100.0% --------------------------------------------------------------------------- ...velop/src/common.rkt:48:2 [59]100.0% [65] 332(0.2%) 0(0.0%) ??? ...s/racket/contract/private/arr-i.rkt:1286:39 assoc-ref [70] 100.0% --------------------------------------------------------------------------- for-loop [51] 0.2% loop [30] 0.5% loop [63] 99.3% [66] 132804(93.2%) 686(0.5%) ??? ...tlies/herbie/develop/src/programs.rkt:129:2 ??? [71] 96.3% bf [72] 3.2% --------------------------------------------------------------------------- map [57] 5.8% localize-error [23] 5.8% eval-prog [48] 27.8% loop [63] 60.6% [67] 4834(3.4%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 ??? [2] 51.4% bigfloat-hash [73] 27.8% parse-loop14 [76] 11.5% f8 [52] 9.2% --------------------------------------------------------------------------- location-do [77] 6.8% for-loop [38] 6.8% loop [3] 14.7% for-loop [32] 28.2% loop [63] 43.5% [68] 2278(1.6%) 642(0.5%) ??? ...tract/private/arrow-higher-order.rkt:357:33 ->flonum [74] 58.2% location-do [77] 6.8% ??? [19] 6.8% --------------------------------------------------------------------------- temp74_0 [64] 100.0% [69] 632(0.4%) 0(0.0%) expand-single (unknown source) expand-capturing-lifts [75] 100.0% --------------------------------------------------------------------------- ??? [65] 100.0% [70] 332(0.2%) 332(0.2%) assoc-ref ...collects/racket/private/dict.rkt:56:0 --------------------------------------------------------------------------- ??? [66] 100.0% [71] 127908(89.7%) 4866(3.4%) ??? ...acket/collects/racket/private/kw.rkt:592:14 ??? [78] 95.4% --------------------------------------------------------------------------- f8 [52] 8.3% ??? [66] 91.7% [72] 4590(3.2%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [99] 92.7% ??? [100] 7.3% --------------------------------------------------------------------------- hash-ref! [67] 100.0% [73] 3830(2.7%) 0(0.0%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:167:0 sig+exp->bigfloat [79] 71.6% bfcanonicalize [80] 28.4% --------------------------------------------------------------------------- ??? [68] 100.0% [74] 1326(0.9%) 1014(0.7%) ->flonum ...s/herbie/develop/src/programs.rkt:36:0 ??? [100] 23.5% --------------------------------------------------------------------------- expand-single [69] 48.0% loop [53] 52.0% [75] 1316(0.9%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [81] 48.0% loop [53] 27.1% dispatch-transformer41 [82] 24.9% --------------------------------------------------------------------------- hash-ref! [67] 100.0% [76] 1004(0.7%) 0(0.0%) parse-loop14 ...s/racket/match/compiler.rkt:418:15 loop [63] 83.4% loop [3] 16.6% --------------------------------------------------------------------------- ??? [68] 100.0% [77] 310(0.2%) 0(0.0%) location-do ...erbie/develop/src/programs.rkt:90:0 ??? [68] 100.0% --------------------------------------------------------------------------- map [57] 0.3% loop [63] 0.3% ??? [71] 99.5% [78] 122632(86.0%) 638(0.4%) ??? ...tract/private/arrow-higher-order.rkt:361:33 bf-complex-exp [83] 77.8% bf-complex-div [84] 16.2% bf-complex-add [85] 3.6% --------------------------------------------------------------------------- bigfloat-hash [73] 100.0% [79] 2742(1.9%) 0(0.0%) sig+exp->bigfloat ...ivate/bigfloat/mpfr.rkt:387:0 new-mpfr [99] 100.0% --------------------------------------------------------------------------- bigfloat-hash [73] 100.0% [80] 1088(0.8%) 1088(0.8%) bfcanonicalize .../private/bigfloat/mpfr.rkt:157:0 --------------------------------------------------------------------------- expand-capturing-lifts [75] 100.0% [81] 632(0.4%) 0(0.0%) lambda-clause-expander (unknown source) finish-bodys [86] 100.0% --------------------------------------------------------------------------- expand-capturing-lifts [75] 100.0% [82] 328(0.2%) 0(0.0%) dispatch-transformer41 (unknown source) apply-transformer52 [87] 100.0% --------------------------------------------------------------------------- ??? [78] 100.0% [83] 95378(66.9%) 1262(0.9%) bf-complex-exp .../develop/src/bigcomplex.rkt:41:0 ??? [92] 91.4% bf* [88] 6.9% ...higher-order.rkt:361:33 [89] 0.4% --------------------------------------------------------------------------- ??? [78] 100.0% [84] 19926(14.0%) 1624(1.1%) bf-complex-div .../develop/src/bigcomplex.rkt:58:0 bf* [88] 53.9% ??? [92] 31.1% ...higher-order.rkt:361:33 [89] 6.8% --------------------------------------------------------------------------- ??? [78] 100.0% [85] 4454(3.1%) 0(0.0%) bf-complex-add .../develop/src/bigcomplex.rkt:24:0 ??? [92] 100.0% --------------------------------------------------------------------------- for-loop [90] 45.3% lambda-clause-expander [81] 54.7% [86] 632(0.4%) 0(0.0%) finish-bodys (unknown source) for-loop [90] 100.0% --------------------------------------------------------------------------- dispatch-transformer41 [82] 100.0% [87] 328(0.2%) 0(0.0%) apply-transformer52 (unknown source) syntax-add-shifts44 [91] 100.0% --------------------------------------------------------------------------- bf-complex-exp [83] 37.9% bf-complex-div [84] 62.1% [88] 17308(12.1%) 306(0.2%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [92] 98.2% --------------------------------------------------------------------------- bf-complex-exp [83] 20.5% bf-complex-div [84] 79.5% [89] 1698(1.2%) 336(0.2%) ...higher-order.rkt:361:33 (unknown source) ??? [93] 80.2% --------------------------------------------------------------------------- loop [53] 2.8% run-module-instance!125 [94] 16.6% finish-bodys [86] 39.8% for-loop [90] 40.8% [90] 988(0.7%) 0(0.0%) for-loop (unknown source) for-loop [90] 40.8% finish-bodys [86] 26.1% run-module-instance!125 [94] 19.4% add-local-binding!37 [95] 10.5% syntax-e [4] 3.3% --------------------------------------------------------------------------- apply-transformer52 [87] 100.0% [91] 328(0.2%) 328(0.2%) syntax-add-shifts44 (unknown source) --------------------------------------------------------------------------- bf-complex-add [85] 3.8% bf-complex-div [84] 5.3% bf* [88] 14.5% bf-complex-exp [83] 74.5% [92] 117104(82.1%) 80956(56.8%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [96] 20.5% bfneg [97] 10.4% --------------------------------------------------------------------------- ...higher-order.rkt:361:33 [89] 100.0% [93] 1362(1.0%) 672(0.5%) ??? ...cts/racket/contract/private/list.rkt:209:10 ??? [98] 50.7% --------------------------------------------------------------------------- for-loop [90] 100.0% [94] 356(0.2%) 356(0.2%) run-module-instance!125 (unknown source) for-loop [90] 85.7% --------------------------------------------------------------------------- for-loop [90] 100.0% [95] 310(0.2%) 310(0.2%) add-local-binding!37 (unknown source) --------------------------------------------------------------------------- ??? [92] 100.0% [96] 24010(16.8%) 1318(0.9%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [99] 70.6% ??? [100] 24.0% --------------------------------------------------------------------------- ??? [92] 100.0% [97] 12138(8.5%) 652(0.5%) bfneg ...-lib/math/private/bigfloat/mpfr.rkt:618:4 new-mpfr [99] 57.4% ??? [100] 37.3% --------------------------------------------------------------------------- ??? [93] 100.0% [98] 690(0.5%) 690(0.5%) ??? ...ects/racket/contract/private/guts.rkt:746:8 --------------------------------------------------------------------------- sig+exp->bigfloat [79] 8.9% bf [72] 13.8% bfneg [97] 22.5% bfmul [96] 54.8% [99] 30898(21.7%) 30898(21.7%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 --------------------------------------------------------------------------- ->flonum [74] 2.9% bf [72] 3.1% bfneg [97] 41.4% bfmul [96] 52.7% [100] 10924(7.7%) 10924(7.7%) ??? /home/p92/racket/collects/ffi/unsafe.rkt:898:4 ---------------------------------------------------------------------------