Profiling results ----------------- Total cpu time observed: 138614ms (out of 140884ms) Number of samples taken: 357 (once every 388ms) (Hiding functions with self<1.0% and local<2.0%: 5 of 101 hidden) ==================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ==================================================================== ??? [9] 100.0% [1] 136630(98.6%) 0(0.0%) profile-thunk16 ...t/pkgs/profile-lib/main.rkt:9:0 run [5] 100.0% -------------------------------------------------------------------- for-loop [56] 1.8% ??? [41] 6.5% for-loop [48] 8.0% location-do [22] 9.8% loop [54] 74.0% [2] 10094(7.3%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:342:33 location-do [22] 98.2% approximate9 [7] 1.8% -------------------------------------------------------------------- location-do [22] 100.0% [3] 1620(1.2%) 0(0.0%) iterate-egraph!7 ...ode/src/core/simplify.rkt:55:0 one-iter [6] 100.0% -------------------------------------------------------------------- f6 [18] 100.0% [4] 354(0.3%) 0(0.0%) taylor-invert ...ut-code/src/core/taylor.rkt:312:0 simplify [8] 100.0% -------------------------------------------------------------------- profile-thunk16 [1] 99.3% [5] 137640(99.3%) 0(0.0%) run ...share/racket/pkgs/profile-lib/main.rkt:39:2 ??? [9] 100.0% -------------------------------------------------------------------- iterate-egraph!7 [3] 100.0% [6] 1620(1.2%) 0(0.0%) one-iter ...an-out-code/src/core/simplify.rkt:66:0 for-loop [10] 61.9% for-loop [11] 38.1% -------------------------------------------------------------------- ??? [2] 100.0% [7] 354(0.3%) 0(0.0%) approximate9 ...-out-code/src/core/taylor.rkt:12:0 f6 [18] 100.0% -------------------------------------------------------------------- taylor-invert [4] 100.0% [8] 354(0.3%) 0(0.0%) simplify ...lean-out-code/src/core/reduce.rkt:16:0 parse-loop12 [49] 100.0% -------------------------------------------------------------------- run [5] 50.3% [9] 137938(99.5%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 49.5% loop [20] 34.8% prepare-points [21] 10.6% run-improve48 [12] 4.4% get-final-combination [13] 0.6% -------------------------------------------------------------------- for-loop [10] 50.0% one-iter [6] 50.0% [10] 1002(0.7%) 360(0.3%) for-loop ...n-out-code/src/core/simplify.rkt:76:12 for-loop [10] 50.0% match-e [14] 32.0% -------------------------------------------------------------------- one-iter [6] 100.0% [11] 618(0.4%) 0(0.0%) for-loop ...n-out-code/src/core/simplify.rkt:117:2 match-e [14] 51.8% for-loop [15] 48.2% -------------------------------------------------------------------- ??? [9] 100.0% [12] 11522(8.3%) 0(0.0%) run-improve48 ...n-out-code/src/mainloop.rkt:286:0 for-loop [16] 70.9% setup-prog!41 [17] 29.1% -------------------------------------------------------------------- ??? [9] 100.0% [13] 1744(1.3%) 0(0.0%) get-final-combination ...de/src/mainloop.rkt:320:0 f6 [18] 100.0% -------------------------------------------------------------------- for-loop [11] 33.3% for-loop [10] 66.7% [14] 962(0.7%) 642(0.5%) match-e ...clean-out-code/src/core/ematch.rkt:46:0 for-loop [19] 33.3% -------------------------------------------------------------------- for-loop [11] 100.0% [15] 298(0.2%) 0(0.0%) for-loop ...n-out-code/src/core/simplify.rkt:99:10 loop! [63] 100.0% -------------------------------------------------------------------- run-improve48 [12] 100.0% [16] 8170(5.9%) 312(0.2%) for-loop .../clean-out-code/src/mainloop.rkt:303:8 run-iter! [23] 96.2% -------------------------------------------------------------------- run-improve48 [12] 100.0% [17] 3352(2.4%) 0(0.0%) setup-prog!41 ...an-out-code/src/mainloop.rkt:63:0 loop [20] 81.2% prepare-points [21] 18.8% -------------------------------------------------------------------- eval-prog [76] 2.5% approximate9 [7] 4.9% hash-ref! [42] 13.2% f6 [18] 19.6% get-final-combination [13] 59.8% [18] 2418(1.7%) 0(0.0%) f6 ...et/collects/racket/match/compiler.rkt:507:40 option-on-expr [60] 46.3% f6 [18] 19.6% loop [54] 13.5% map [24] 13.2% taylor-invert [4] 4.9% location-do [22] 2.5% -------------------------------------------------------------------- match-e [14] 100.0% [19] 320(0.2%) 0(0.0%) for-loop ...ean-out-code/src/core/ematch.rkt:59:11 loop [54] 100.0% -------------------------------------------------------------------- setup-prog!41 [17] 2.8% ??? [9] 97.2% [20] 98076(70.8%) 0(0.0%) loop ...herbie/clean-out-code/src/points.rkt:180:2 loop [25] 43.1% make-exacts* [37] 29.3% loop [38] 26.9% for-loop [28] 0.7% -------------------------------------------------------------------- setup-prog!41 [17] 2.1% ??? [9] 97.9% [21] 29948(21.6%) 0(0.0%) prepare-points ...an-out-code/src/points.rkt:156:0 loop [25] 39.3% make-exacts* [37] 31.3% loop [38] 28.3% for-loop [28] 1.1% -------------------------------------------------------------------- f6 [18] 3.4% location-do [22] 4.7% ??? [2] 91.9% [22] 10452(7.5%) 4496(3.2%) location-do ...acket/private/norm-define.rkt:53:83 ??? [88] 13.3% ??? [2] 8.8% ??? [29] 7.7% return/no-unsupplied [89] 7.0% iterate-egraph!7 [3] 5.4% location-do [22] 4.7% list-member? [31] 3.5% ??? [32] 3.4% loop [34] 3.2% -------------------------------------------------------------------- for-loop [16] 100.0% [23] 7858(5.7%) 0(0.0%) run-iter! ...clean-out-code/src/mainloop.rkt:267:0 finalize-iter! [26] 57.9% simplify! [27] 24.9% localize-error [30] 8.8% gen-series! [33] 4.5% gen-rewrites! [36] 3.9% -------------------------------------------------------------------- f6 [18] 100.0% [24] 320(0.2%) 0(0.0%) map ...acket/collects/racket/private/map.rkt:20:13 ??? [35] 50.0% loop [54] 50.0% -------------------------------------------------------------------- prepare-points [21] 21.8% loop [20] 78.2% [25] 54024(39.0%) 0(0.0%) loop ...herbie/clean-out-code/src/points.rkt:124:2 make-exacts* [37] 59.3% loop [38] 40.7% -------------------------------------------------------------------- run-iter! [23] 100.0% [26] 4550(3.3%) 0(0.0%) finalize-iter! ...-out-code/src/mainloop.rkt:221:0 ??? [39] 100.0% -------------------------------------------------------------------- run-iter! [23] 100.0% [27] 1954(1.4%) 0(0.0%) simplify! ...clean-out-code/src/mainloop.rkt:181:0 for-loop [40] 100.0% -------------------------------------------------------------------- prepare-points [21] 31.2% loop [20] 68.8% [28] 1024(0.7%) 0(0.0%) for-loop ...e/clean-out-code/src/points.rkt:195:19 ??? [41] 100.0% -------------------------------------------------------------------- location-do [22] 100.0% [29] 810(0.6%) 0(0.0%) ??? ...-racket/utils/simple-result-arrow.rkt:60:15 ??? [96] 100.0% -------------------------------------------------------------------- run-iter! [23] 100.0% [30] 690(0.5%) 0(0.0%) localize-error ...-code/src/core/localize.rkt:47:0 hash-ref! [42] 100.0% -------------------------------------------------------------------- location-do [22] 100.0% [31] 362(0.3%) 362(0.3%) list-member? ...llects/racket/private/set.rkt:24:0 -------------------------------------------------------------------- location-do [22] 100.0% [32] 358(0.3%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:737:4 ??? [43] 100.0% -------------------------------------------------------------------- run-iter! [23] 100.0% [33] 354(0.3%) 0(0.0%) gen-series! ...ean-out-code/src/mainloop.rkt:151:0 for-loop [44] 100.0% -------------------------------------------------------------------- location-do [22] 100.0% [34] 334(0.2%) 0(0.0%) loop .../herbie/clean-out-code/src/points.rkt:65:6 random-exp [45] 100.0% -------------------------------------------------------------------- map [24] 33.3% loop [54] 66.7% [35] 320(0.2%) 0(0.0%) ??? ...ket/collects/racket/private/list.rkt:323:18 hash-ref! [42] 100.0% -------------------------------------------------------------------- run-iter! [23] 100.0% [36] 310(0.2%) 0(0.0%) gen-rewrites! ...n-out-code/src/mainloop.rkt:164:0 for-loop [46] 100.0% -------------------------------------------------------------------- prepare-points [21] 13.4% loop [20] 41.0% loop [25] 45.7% [37] 70104(50.6%) 328(0.2%) make-exacts* ...lean-out-code/src/points.rkt:104:0 loop [54] 99.5% -------------------------------------------------------------------- prepare-points [21] 14.9% loop [25] 38.7% loop [20] 46.4% [38] 56896(41.0%) 0(0.0%) loop ...herbie/clean-out-code/src/points.rkt:107:4 loop [54] 99.4% ??? [81] 0.6% -------------------------------------------------------------------- finalize-iter! [26] 100.0% [39] 4550(3.3%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:357:18 for-loop [47] 86.2% atab-add-altns [50] 13.8% -------------------------------------------------------------------- simplify! [27] 100.0% [40] 1954(1.4%) 0(0.0%) for-loop .../clean-out-code/src/mainloop.rkt:185:6 for-loop [48] 82.9% debug-print [52] 17.1% -------------------------------------------------------------------- for-loop [28] 100.0% [41] 1024(0.7%) 370(0.3%) ??? .../herbie/clean-out-code/src/points.rkt:172:7 ??? [2] 63.9% -------------------------------------------------------------------- ??? [35] 34.8% localize-error [30] 65.2% [42] 690(0.5%) 0(0.0%) hash-ref! ...racket/private/more-scheme.rkt:373:13 parse-loop12 [49] 76.8% f6 [18] 23.2% -------------------------------------------------------------------- ??? [32] 100.0% [43] 358(0.3%) 358(0.3%) ??? ...private/unconstrained-domain-arrow.rkt:74:4 -------------------------------------------------------------------- gen-series! [33] 100.0% [44] 354(0.3%) 0(0.0%) for-loop .../clean-out-code/src/mainloop.rkt:157:7 taylor-alt [51] 100.0% -------------------------------------------------------------------- loop [34] 100.0% [45] 334(0.2%) 334(0.2%) random-exp .../clean-out-code/src/common.rkt:243:0 -------------------------------------------------------------------- gen-rewrites! [36] 100.0% [46] 310(0.2%) 0(0.0%) for-loop ...clean-out-code/src/mainloop.rkt:169:11 ??? [53] 100.0% -------------------------------------------------------------------- ??? [39] 100.0% [47] 3922(2.8%) 0(0.0%) for-loop ...n-out-code/src/core/alt-table.rkt:51:2 atab-add-altn [55] 100.0% -------------------------------------------------------------------- for-loop [40] 100.0% [48] 1620(1.2%) 0(0.0%) for-loop .../clean-out-code/src/mainloop.rkt:209:8 ??? [2] 100.0% -------------------------------------------------------------------- simplify [8] 33.9% hash-ref! [42] 66.1% [49] 1044(0.8%) 354(0.3%) parse-loop12 ...s/racket/match/compiler.rkt:418:15 loop [54] 66.1% -------------------------------------------------------------------- ??? [39] 100.0% [50] 628(0.5%) 0(0.0%) atab-add-altns ...code/src/core/alt-table.rkt:50:0 atab-add-altn [55] 100.0% -------------------------------------------------------------------- for-loop [44] 100.0% [51] 354(0.3%) 0(0.0%) taylor-alt ...lean-out-code/src/mainloop.rkt:136:0 for-loop [56] 100.0% -------------------------------------------------------------------- for-loop [40] 100.0% [52] 334(0.2%) 0(0.0%) debug-print .../clean-out-code/src/debug.rkt:107:0 for-loop [57] 100.0% -------------------------------------------------------------------- for-loop [46] 100.0% [53] 310(0.2%) 0(0.0%) ??? ...bie/clean-out-code/src/alternative.rkt:60:0 rewriter [58] 100.0% -------------------------------------------------------------------- map [24] 0.0% option-on-expr [60] 0.0% f6 [18] 0.0% for-loop [19] 0.0% parse-loop12 [49] 0.0% loop! [63] 0.1% loop [38] 1.8% make-exacts* [37] 2.1% loop [54] 95.9% [54] 128348(92.6%) 1008(0.7%) loop ...cket/collects/racket/private/map.rkt:25:19 loop [54] 95.9% ??? [81] 3.7% loop! [63] 0.1% ??? [2] 0.0% ??? [35] 0.0% ??? [90] 0.0% option-on-expr [60] 0.0% -------------------------------------------------------------------- atab-add-altns [50] 13.8% for-loop [47] 86.2% [55] 4550(3.3%) 0(0.0%) atab-add-altn ...code/src/core/alt-table.rkt:208:0 best-and-tied-at-points [59]100.0% -------------------------------------------------------------------- taylor-alt [51] 100.0% [56] 354(0.3%) 0(0.0%) for-loop .../clean-out-code/src/mainloop.rkt:142:6 ??? [2] 100.0% -------------------------------------------------------------------- debug-print [52] 100.0% [57] 334(0.2%) 0(0.0%) for-loop ...bie/clean-out-code/src/debug.rkt:113:2 write-proc [61] 100.0% -------------------------------------------------------------------- matcher [68] 50.0% ??? [53] 50.0% [58] 310(0.2%) 0(0.0%) rewriter ...an-out-code/src/core/matcher.rkt:131:2 for-loop [62] 100.0% -------------------------------------------------------------------- atab-add-altn [55] 100.0% [59] 4550(3.3%) 654(0.5%) best-and-tied-at-points ...ore/alt-table.rkt:122:0 errors [64] 85.6% -------------------------------------------------------------------- loop [54] 18.7% f6 [18] 81.3% [60] 1744(1.3%) 0(0.0%) option-on-expr ...t-code/src/core/regimes.rkt:83:0 sort-context-on-expr [65] 60.8% pick-errors [66] 20.5% loop [54] 18.7% -------------------------------------------------------------------- for-loop [57] 100.0% [61] 334(0.2%) 334(0.2%) write-proc ...an-out-code/src/alternative.rkt:20:9 -------------------------------------------------------------------- rewriter [58] 100.0% [62] 310(0.2%) 0(0.0%) for-loop ...n-out-code/src/core/matcher.rkt:134:10 matcher [68] 50.0% type-of [67] 50.0% -------------------------------------------------------------------- loop [54] 50.0% for-loop [15] 50.0% [63] 298(0.2%) 0(0.0%) loop! ...e/clean-out-code/src/core/enode.rkt:190:2 loop [54] 50.0% update-en-expr [69] 50.0% -------------------------------------------------------------------- best-and-tied-at-points [59]100.0% [64] 3896(2.8%) 0(0.0%) errors ...rbie/clean-out-code/src/points.rkt:203:0 for-loop [70] 83.0% eval-prog [76] 17.0% -------------------------------------------------------------------- option-on-expr [60] 100.0% [65] 1060(0.8%) 0(0.0%) sort-context-on-expr ...t-code/src/points.rkt:90:0 ??? [71] 100.0% -------------------------------------------------------------------- option-on-expr [60] 100.0% [66] 358(0.3%) 0(0.0%) pick-errors ...out-code/src/core/regimes.rkt:202:0 point->alt [72] 100.0% -------------------------------------------------------------------- for-loop [62] 100.0% [67] 310(0.2%) 0(0.0%) type-of .../clean-out-code/src/type-check.rkt:34:0 ??? [73] 100.0% -------------------------------------------------------------------- for-loop [74] 50.0% for-loop [62] 50.0% [68] 310(0.2%) 0(0.0%) matcher ...ean-out-code/src/core/matcher.rkt:168:2 for-loop [74] 50.0% rewriter [58] 50.0% -------------------------------------------------------------------- loop! [63] 100.0% [69] 298(0.2%) 298(0.2%) update-en-expr ...t-code/src/core/egraph.rkt:212:0 -------------------------------------------------------------------- errors [64] 100.0% [70] 3234(2.3%) 0(0.0%) for-loop ...ie/clean-out-code/src/points.rkt:206:4 ??? [81] 100.0% -------------------------------------------------------------------- sort-context-on-expr [65] 100.0% [71] 1060(0.8%) 0(0.0%) ??? ...cket/collects/racket/private/sort.rkt:403:3 generic-sort/key [75] 100.0% -------------------------------------------------------------------- pick-errors [66] 100.0% [72] 358(0.3%) 0(0.0%) point->alt ...-out-code/src/core/regimes.rkt:189:0 eval-prog [76] 100.0% -------------------------------------------------------------------- type-of [67] 100.0% [73] 310(0.2%) 0(0.0%) ??? ...ts/racket/contract/private/arr-i.rkt:804:10 for-loop [77] 100.0% -------------------------------------------------------------------- matcher [68] 100.0% [74] 310(0.2%) 0(0.0%) for-loop ...n-out-code/src/core/matcher.rkt:184:21 matcher [68] 100.0% -------------------------------------------------------------------- ??? [71] 100.0% [75] 1060(0.8%) 0(0.0%) generic-sort/key .../racket/private/sort.rkt:185:2 copying-mergesort [78] 69.4% loop [79] 30.6% -------------------------------------------------------------------- point->alt [72] 35.1% errors [64] 64.9% [76] 1020(0.7%) 662(0.5%) eval-prog ...clean-out-code/src/programs.rkt:109:0 f6 [18] 35.1% -------------------------------------------------------------------- ??? [73] 100.0% [77] 310(0.2%) 310(0.2%) for-loop ...racket/contract/private/hash.rkt:239:6 -------------------------------------------------------------------- generic-sort/key [75] 25.0% copying-mergesort [78] 75.0% [78] 736(0.5%) 0(0.0%) copying-mergesort ...racket/private/sort.rkt:130:8 copying-mergesort [78] 75.0% jloop [80] 25.0% -------------------------------------------------------------------- generic-sort/key [75] 100.0% [79] 324(0.2%) 0(0.0%) loop ...ket/collects/racket/private/sort.rkt:97:12 ??? [81] 100.0% -------------------------------------------------------------------- copying-mergesort [78] 100.0% [80] 736(0.5%) 0(0.0%) jloop ...t/collects/racket/private/sort.rkt:121:23 ??? [81] 100.0% -------------------------------------------------------------------- loop [38] 0.3% loop [79] 0.3% jloop [80] 0.6% for-loop [70] 2.6% loop [54] 96.2% [81] 122848(88.6%) 1450(1.0%) ??? ...erbie/clean-out-code/src/programs.rkt:129:2 ??? [82] 91.4% ??? [83] 7.1% -------------------------------------------------------------------- ??? [81] 100.0% [82] 112338(81.0%) 10294(7.4%) ??? ...acket/collects/racket/private/kw.rkt:444:14 ??? [90] 73.0% bffmod [84] 11.7% bf* [87] 3.8% ??? [86] 0.8% -------------------------------------------------------------------- ??? [81] 100.0% [83] 8702(6.3%) 660(0.5%) ??? ...contract/private/../../private/kw.rkt:761:9 real->bigfloat [85] 92.4% -------------------------------------------------------------------- ??? [82] 100.0% [84] 13112(9.5%) 0(0.0%) bffmod ...ean-out-code/src/syntax/syntax.rkt:381:0 ??? [90] 71.4% bf* [87] 28.6% -------------------------------------------------------------------- ??? [83] 100.0% [85] 8042(5.8%) 6974(5.0%) real->bigfloat .../private/bigfloat/mpfr.rkt:451:0 ??? [96] 13.3% -------------------------------------------------------------------- ??? [82] 100.0% [86] 936(0.7%) 322(0.2%) ??? ...clean-out-code/src/syntax/syntax.rkt:196:17 return/no-unsupplied [89] 33.5% ??? [88] 32.1% -------------------------------------------------------------------- bffmod [84] 47.0% ??? [82] 53.0% [87] 7966(5.7%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [90] 100.0% -------------------------------------------------------------------- ??? [86] 17.8% location-do [22] 82.2% [88] 1690(1.2%) 958(0.7%) ??? ...racket/collects/racket/private/kw.rkt:761:9 ??? [91] 43.3% -------------------------------------------------------------------- ??? [86] 30.1% location-do [22] 69.9% [89] 1042(0.8%) 684(0.5%) return/no-unsupplied ...ct/private/arr-i.rkt:533:0 filter [92] 34.4% -------------------------------------------------------------------- loop [54] 0.7% bf* [87] 8.0% bffmod [84] 9.4% ??? [82] 82.0% [90] 100054(72.2%) 67900(49.0%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [93] 18.4% bfasin [94] 10.7% bffloor [95] 3.1% -------------------------------------------------------------------- ??? [88] 100.0% [91] 732(0.5%) 732(0.5%) ??? ...s/racket/contract/private/arr-i.rkt:1233:27 -------------------------------------------------------------------- return/no-unsupplied [89] 100.0% [92] 358(0.3%) 358(0.3%) filter ...t/collects/racket/private/list.rkt:256:2 -------------------------------------------------------------------- ??? [90] 100.0% [93] 18370(13.3%) 14602(10.5%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:819:4 ??? [96] 20.5% -------------------------------------------------------------------- ??? [90] 100.0% [94] 10662(7.7%) 8322(6.0%) bfasin ...lib/math/private/bigfloat/mpfr.rkt:616:4 ??? [96] 21.9% -------------------------------------------------------------------- ??? [90] 100.0% [95] 3122(2.3%) 1728(1.2%) bffloor ...ib/math/private/bigfloat/mpfr.rkt:714:4 ??? [96] 44.7% -------------------------------------------------------------------- ??? [29] 8.6% real->bigfloat [85] 11.4% bffloor [95] 14.9% bfasin [94] 24.9% bfmul [93] 40.2% [96] 9380(6.8%) 9380(6.8%) ??? .../share/racket/collects/ffi/unsafe.rkt:861:4 --------------------------------------------------------------------