Profiling results ----------------- Total cpu time observed: 54758ms (out of 55552ms) Number of samples taken: 150 (once every 365ms) (Hiding functions with self<1.0% and local<2.0%: 2 of 101 hidden) ==================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ==================================================================== ??? [28] 100.0% [1] 53786(98.2%) 0(0.0%) profile-thunk16 ...t/pkgs/profile-lib/main.rkt:9:0 run [9] 100.0% -------------------------------------------------------------------- ??? [28] 100.0% [2] 10080(18.4%) 0(0.0%) run-improve50 ...ie/develop/src/mainloop.rkt:234:0 for-loop [10] 86.2% loop [31] 13.8% -------------------------------------------------------------------- location-do [32] 100.0% [3] 972(1.8%) 0(0.0%) loop ...herbie/develop/src/core/simplify.rkt:210:2 pass [11] 100.0% -------------------------------------------------------------------- hash-ref! [78] 100.0% [4] 684(1.2%) 0(0.0%) ??? ...tlies/herbie/develop/src/programs.rkt:184:5 loop [48] 100.0% -------------------------------------------------------------------- loop [48] 100.0% [5] 336(0.6%) 0(0.0%) ??? ...ket/collects/racket/private/list.rkt:323:18 hash-ref! [78] 100.0% -------------------------------------------------------------------- hash-ref! [78] 100.0% [6] 332(0.6%) 0(0.0%) ??? ...s/herbie/develop/src/core/taylor.rkt:319:33 for-loop [13] 100.0% -------------------------------------------------------------------- hash-ref! [78] 100.0% [7] 332(0.6%) 0(0.0%) ??? ...s/herbie/develop/src/core/taylor.rkt:335:33 for-loop [12] 100.0% -------------------------------------------------------------------- hash-ref! [78] 100.0% [8] 326(0.6%) 0(0.0%) ??? ...s/herbie/develop/src/core/taylor.rkt:282:26 for-loop [14] 100.0% -------------------------------------------------------------------- profile-thunk16 [1] 99.4% [9] 54122(98.8%) 0(0.0%) run ...share/racket/pkgs/profile-lib/main.rkt:39:2 ??? [28] 100.0% -------------------------------------------------------------------- run-improve50 [2] 100.0% [10] 8688(15.9%) 0(0.0%) for-loop ...herbie/develop/src/mainloop.rkt:249:10 run-iter! [15] 100.0% -------------------------------------------------------------------- loop [3] 100.0% [11] 972(1.8%) 0(0.0%) pass ...herbie/develop/src/core/simplify.rkt:200:2 for-loop [16] 100.0% -------------------------------------------------------------------- ??? [7] 100.0% [12] 332(0.6%) 0(0.0%) for-loop ...bie/develop/src/core/taylor.rkt:338:46 hash-ref! [78] 100.0% -------------------------------------------------------------------- ??? [6] 100.0% [13] 332(0.6%) 0(0.0%) for-loop ...bie/develop/src/core/taylor.rkt:321:46 simplify [17] 100.0% -------------------------------------------------------------------- ??? [8] 100.0% [14] 326(0.6%) 0(0.0%) for-loop ...bie/develop/src/core/taylor.rkt:282:52 hash-ref! [78] 100.0% -------------------------------------------------------------------- for-loop [10] 100.0% [15] 8688(15.9%) 0(0.0%) run-iter! ...herbie/develop/src/mainloop.rkt:215:0 simplify! [18] 61.8% finalize-iter! [19] 18.8% localize-error [20] 7.7% gen-series! [21] 7.6% choose-best-alt! [23] 4.2% -------------------------------------------------------------------- pass [11] 100.0% [16] 972(1.8%) 0(0.0%) for-loop ...e/develop/src/core/simplify.rkt:203:29 argmin [22] 65.4% loop [24] 34.6% -------------------------------------------------------------------- hash-ref! [78] 49.5% for-loop [13] 50.5% [17] 658(1.2%) 0(0.0%) simplify ...erbie/develop/src/core/reduce.rkt:15:0 f6 [83] 50.5% parse-loop12 [84] 49.5% -------------------------------------------------------------------- run-iter! [15] 100.0% [18] 5366(9.8%) 0(0.0%) simplify! ...herbie/develop/src/mainloop.rkt:155:0 for-loop [25] 100.0% -------------------------------------------------------------------- run-iter! [15] 100.0% [19] 1630(3.0%) 0(0.0%) finalize-iter! ...e/develop/src/mainloop.rkt:169:0 ??? [57] 100.0% -------------------------------------------------------------------- run-iter! [15] 100.0% [20] 672(1.2%) 0(0.0%) localize-error ...velop/src/core/localize.rkt:47:0 hash-ref! [78] 100.0% -------------------------------------------------------------------- run-iter! [15] 100.0% [21] 658(1.2%) 0(0.0%) gen-series! ...rbie/develop/src/mainloop.rkt:129:0 for-loop [27] 100.0% -------------------------------------------------------------------- for-loop [16] 100.0% [22] 636(1.2%) 0(0.0%) argmin ...re/racket/collects/racket/list.rkt:743:0 for-loop [26] 100.0% -------------------------------------------------------------------- run-iter! [15] 100.0% [23] 362(0.7%) 0(0.0%) choose-best-alt! ...develop/src/mainloop.rkt:115:0 ??? [57] 100.0% -------------------------------------------------------------------- for-loop [16] 100.0% [24] 336(0.6%) 0(0.0%) loop ...hare/racket/collects/racket/list.rkt:729:4 for-loop [26] 100.0% -------------------------------------------------------------------- simplify! [18] 100.0% [25] 5366(9.8%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:159:6 ??? [28] 100.0% -------------------------------------------------------------------- loop [24] 34.6% argmin [22] 65.4% [26] 972(1.8%) 0(0.0%) for-loop .../herbie/develop/src/programs.rkt:200:2 location-do [32] 65.4% ??? [30] 34.6% -------------------------------------------------------------------- gen-series! [21] 100.0% [27] 658(1.2%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:135:7 taylor-alt [29] 100.0% -------------------------------------------------------------------- for-loop [25] 3.3% run [9] 48.1% [28] 54758(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 47.5% loop [31] 40.5% run-improve50 [2] 7.6% location-do [32] 4.4% -------------------------------------------------------------------- for-loop [27] 100.0% [29] 658(1.2%) 0(0.0%) taylor-alt ...es/herbie/develop/src/glue.rkt:139:0 for-loop [33] 100.0% -------------------------------------------------------------------- for-loop [26] 100.0% [30] 336(0.6%) 336(0.6%) ??? ...ts/racket/contract/private/arr-i.rkt:804:10 -------------------------------------------------------------------- run-improve50 [2] 3.1% ??? [28] 96.9% [31] 45434(83.0%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:193:2 loop [34] 50.4% make-exacts* [39] 32.1% loop [40] 16.8% for-loop [38] 0.7% -------------------------------------------------------------------- for-loop [26] 3.2% ??? [68] 7.4% for-loop [36] 17.3% location-do [32] 28.6% ??? [28] 43.5% [32] 6660(12.2%) 0(0.0%) location-do ...acket/private/norm-define.rkt:53:83 iterate-egraph!13 [35] 37.8% location-do [32] 28.6% for-loop [36] 17.3% ??? [68] 7.4% loop [3] 5.7% ??? [37] 3.2% -------------------------------------------------------------------- taylor-alt [29] 100.0% [33] 658(1.2%) 0(0.0%) for-loop ...lies/herbie/develop/src/glue.rkt:141:2 ??? [68] 100.0% -------------------------------------------------------------------- loop [31] 100.0% [34] 22896(41.8%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:151:2 loop [40] 56.7% make-exacts* [39] 38.6% eval-prog [47] 4.8% -------------------------------------------------------------------- location-do [32] 100.0% [35] 5030(9.2%) 0(0.0%) iterate-egraph!13 ...p/src/core/simplify.rkt:102:0 one-iter [41] 100.0% -------------------------------------------------------------------- location-do [32] 100.0% [36] 2410(4.4%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:63:12 location-do [32] 100.0% -------------------------------------------------------------------- location-do [32] 100.0% [37] 636(1.2%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:737:4 for-loop [42] 100.0% -------------------------------------------------------------------- loop [31] 100.0% [38] 336(0.6%) 0(0.0%) for-loop ...s/herbie/develop/src/points.rkt:200:24 for-loop [43] 100.0% -------------------------------------------------------------------- loop [34] 37.7% loop [31] 62.3% [39] 23410(42.8%) 0(0.0%) make-exacts* ...erbie/develop/src/points.rkt:138:0 loop [48] 50.4% map [44] 48.2% eval-prog [47] 1.4% -------------------------------------------------------------------- loop [31] 37.0% loop [34] 63.0% [40] 20600(37.6%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:141:4 map [44] 100.0% -------------------------------------------------------------------- iterate-egraph!13 [35] 100.0% [41] 5030(9.2%) 394(0.7%) one-iter ...ie/develop/src/core/simplify.rkt:113:0 for-loop [45] 49.1% loop [46] 35.9% apply-match [49] 7.2% -------------------------------------------------------------------- ??? [37] 100.0% [42] 636(1.2%) 636(1.2%) for-loop ...acket/contract/private/list.rkt:743:12 -------------------------------------------------------------------- for-loop [38] 100.0% [43] 336(0.6%) 336(0.6%) for-loop ...s/herbie/develop/src/points.rkt:201:26 -------------------------------------------------------------------- f6 [83] 2.1% make-exacts* [39] 34.6% loop [40] 63.3% [44] 32548(59.4%) 0(0.0%) map ...acket/collects/racket/private/map.rkt:20:13 loop [48] 97.6% ??? [88] 2.4% -------------------------------------------------------------------- for-loop [45] 50.0% one-iter [41] 50.0% [45] 2468(4.5%) 696(1.3%) for-loop ...e/develop/src/core/simplify.rkt:123:12 for-loop [45] 50.0% match-e [51] 35.9% -------------------------------------------------------------------- one-iter [41] 100.0% [46] 1808(3.3%) 358(0.7%) loop ...cket/collects/racket/private/map.rkt:52:19 apply-match [49] 80.2% -------------------------------------------------------------------- make-exacts* [39] 23.5% loop [34] 76.5% [47] 1422(2.6%) 0(0.0%) eval-prog ...herbie/develop/src/programs.rkt:149:0 hash-ref! [78] 75.5% go [50] 24.5% -------------------------------------------------------------------- parse-loop12 [84] 0.0% make-exacts* [39] 0.2% ??? [4] 1.5% map [44] 3.2% loop [48] 95.0% [48] 44250(80.8%) 338(0.6%) loop ...cket/collects/racket/private/map.rkt:25:19 loop [48] 95.0% ??? [88] 3.3% hash-ref! [78] 1.5% ??? [92] 0.1% ->flonum [91] 0.1% ??? [5] 0.0% -------------------------------------------------------------------- one-iter [41] 19.9% loop [46] 80.1% [49] 1810(3.3%) 0(0.0%) apply-match ...develop/src/core/simplify.rkt:129:2 merge-egraph-nodes! [55] 41.7% for-loop [52] 20.4% substitute-e [53] 19.9% match-e [51] 18.0% -------------------------------------------------------------------- eval-prog [47] 100.0% [50] 348(0.6%) 348(0.6%) go .../collects/racket/private/qq-and-or.rkt:50:20 -------------------------------------------------------------------- for-loop [59] 7.8% apply-match [49] 7.8% for-loop [45] 84.5% [51] 2098(3.8%) 360(0.7%) match-e ...herbie/develop/src/core/ematch.rkt:46:0 for-loop [54] 57.8% for-loop [59] 17.3% variable? [56] 7.8% -------------------------------------------------------------------- apply-match [49] 100.0% [52] 370(0.7%) 0(0.0%) for-loop ...e/develop/src/core/simplify.rkt:143:10 merge-egraph-nodes! [55] 100.0% -------------------------------------------------------------------- substitute-e [53] 50.0% apply-match [49] 50.0% [53] 360(0.7%) 0(0.0%) substitute-e ...e/develop/src/core/ematch.rkt:71:0 substitute-e [53] 50.0% ??? [57] 50.0% -------------------------------------------------------------------- match-e [51] 100.0% [54] 1376(2.5%) 358(0.7%) for-loop ...rbie/develop/src/core/ematch.rkt:59:11 for-loop [59] 47.8% foldl [60] 26.2% -------------------------------------------------------------------- for-loop [52] 32.9% apply-match [49] 67.1% [55] 1124(2.1%) 0(0.0%) merge-egraph-nodes! ...p/src/core/egraph.rkt:152:0 update-leader! [58] 100.0% -------------------------------------------------------------------- match-e [51] 100.0% [56] 326(0.6%) 0(0.0%) variable? ...e/develop/src/syntax/syntax.rkt:529:0 ??? [57] 100.0% -------------------------------------------------------------------- variable? [56] 12.2% substitute-e [53] 13.4% choose-best-alt! [23] 13.5% finalize-iter! [19] 60.9% [57] 2678(4.9%) 686(1.3%) ??? ...contract/private/arrow-val-first.rkt:357:18 for-loop [61] 60.9% ??? [64] 13.5% -------------------------------------------------------------------- merge-egraph-nodes! [55] 100.0% [58] 1124(2.1%) 0(0.0%) update-leader! ...evelop/src/core/egraph.rkt:217:0 for-loop [62] 100.0% -------------------------------------------------------------------- match-e [51] 35.5% for-loop [54] 64.5% [59] 1020(1.9%) 0(0.0%) for-loop ...rbie/develop/src/core/ematch.rkt:65:37 ??? [63] 68.0% match-e [51] 32.0% -------------------------------------------------------------------- for-loop [54] 100.0% [60] 360(0.7%) 0(0.0%) foldl ...et/collects/racket/private/list.rkt:229:4 merge2 [65] 100.0% -------------------------------------------------------------------- ??? [57] 100.0% [61] 1630(3.0%) 0(0.0%) for-loop ...ie/develop/src/core/alt-table.rkt:51:2 atab-add-altn [66] 100.0% -------------------------------------------------------------------- update-leader! [58] 100.0% [62] 1124(2.1%) 0(0.0%) for-loop ...rbie/develop/src/core/egraph.rkt:222:6 for-loop [67] 100.0% -------------------------------------------------------------------- for-loop [59] 100.0% [63] 694(1.3%) 362(0.7%) ??? ...ies/herbie/develop/src/core/ematch.rkt:50:5 for-loop [69] 47.8% -------------------------------------------------------------------- ??? [57] 100.0% [64] 362(0.7%) 0(0.0%) ??? .../herbie/develop/src/core/alt-table.rkt:54:0 ??? [68] 100.0% -------------------------------------------------------------------- foldl [60] 100.0% [65] 360(0.7%) 360(0.7%) merge2 .../herbie/develop/src/core/ematch.rkt:31:0 -------------------------------------------------------------------- for-loop [61] 100.0% [66] 1630(3.0%) 0(0.0%) atab-add-altn ...elop/src/core/alt-table.rkt:208:0 best-and-tied-at-points [71] 61.6% override-at-pnts [73] 38.4% -------------------------------------------------------------------- for-loop [62] 100.0% [67] 1124(2.1%) 0(0.0%) for-loop ...rbie/develop/src/core/egraph.rkt:223:8 hash-update! [70] 100.0% -------------------------------------------------------------------- location-do [32] 32.3% for-loop [33] 32.3% ??? [64] 35.5% [68] 1020(1.9%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:342:33 best-alt [74] 35.5% location-do [32] 32.3% approximate9 [72] 32.3% -------------------------------------------------------------------- ??? [63] 100.0% [69] 332(0.6%) 332(0.6%) for-loop ...erbie/develop/src/core/ematch.rkt:51:7 -------------------------------------------------------------------- for-loop [67] 100.0% [70] 1124(2.1%) 0(0.0%) hash-update! ...ket/private/more-scheme.rkt:362:13 ??? [75] 100.0% -------------------------------------------------------------------- atab-add-altn [66] 100.0% [71] 1004(1.8%) 0(0.0%) best-and-tied-at-points ...ore/alt-table.rkt:122:0 errors [81] 100.0% -------------------------------------------------------------------- ??? [68] 100.0% [72] 658(1.2%) 0(0.0%) approximate9 ...e/develop/src/core/taylor.rkt:12:0 loop [76] 100.0% -------------------------------------------------------------------- atab-add-altn [66] 100.0% [73] 626(1.1%) 0(0.0%) override-at-pnts ...p/src/core/alt-table.rkt:145:0 errors [81] 100.0% -------------------------------------------------------------------- ??? [68] 100.0% [74] 362(0.7%) 0(0.0%) best-alt ...tlies/herbie/develop/src/glue.rkt:76:0 loop [77] 100.0% -------------------------------------------------------------------- hash-update! [70] 100.0% [75] 1124(2.1%) 0(0.0%) ??? ...s/herbie/develop/src/core/egraph.rkt:225:24 for-loop [79] 100.0% -------------------------------------------------------------------- approximate9 [72] 100.0% [76] 658(1.2%) 0(0.0%) loop ...es/herbie/develop/src/core/taylor.rkt:83:4 hash-ref! [78] 100.0% -------------------------------------------------------------------- best-alt [74] 100.0% [77] 362(0.7%) 0(0.0%) loop ...htlies/herbie/develop/src/common.rkt:142:2 composed [80] 100.0% -------------------------------------------------------------------- for-loop [14] 4.5% for-loop [12] 4.6% ??? [5] 7.0% ??? [85] 9.1% loop [76] 9.1% loop [48] 17.7% localize-error [20] 21.0% eval-prog [47] 27.0% [78] 2404(4.4%) 0(0.0%) hash-ref! ...racket/private/more-scheme.rkt:373:13 bigfloat-hash [82] 27.0% f6 [83] 21.0% ??? [4] 17.7% ??? [85] 9.1% parse-loop12 [84] 7.0% ??? [7] 4.6% ??? [6] 4.6% ??? [8] 4.5% simplify [17] 4.5% -------------------------------------------------------------------- ??? [75] 100.0% [79] 1124(2.1%) 1124(2.1%) for-loop ...bie/develop/src/core/egraph.rkt:226:26 -------------------------------------------------------------------- loop [77] 100.0% [80] 362(0.7%) 0(0.0%) composed ...ollects/racket/private/list.rkt:321:16 errors [81] 100.0% -------------------------------------------------------------------- composed [80] 18.2% override-at-pnts [73] 31.4% best-and-tied-at-points [71] 50.4% [81] 1992(3.6%) 0(0.0%) errors ...lies/herbie/develop/src/points.rkt:218:0 for-loop [86] 100.0% -------------------------------------------------------------------- hash-ref! [78] 100.0% [82] 1074(2.0%) 0(0.0%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:165:0 sig+exp->bigfloat [87] 100.0% -------------------------------------------------------------------- simplify [17] 33.1% hash-ref! [78] 66.9% [83] 1004(1.8%) 332(0.6%) f6 ...et/collects/racket/match/compiler.rkt:507:40 map [44] 66.9% -------------------------------------------------------------------- simplify [17] 49.2% hash-ref! [78] 50.8% [84] 662(1.2%) 326(0.6%) parse-loop12 ...s/racket/match/compiler.rkt:418:15 loop [48] 50.8% -------------------------------------------------------------------- hash-ref! [78] 100.0% [85] 658(1.2%) 0(0.0%) ??? ...es/herbie/develop/src/core/taylor.rkt:43:15 hash-ref! [78] 100.0% -------------------------------------------------------------------- errors [81] 100.0% [86] 1992(3.6%) 326(0.6%) for-loop ...es/herbie/develop/src/points.rkt:221:4 ??? [88] 66.9% ->flonum [91] 16.8% -------------------------------------------------------------------- bigfloat-hash [82] 100.0% [87] 1074(2.0%) 1074(2.0%) sig+exp->bigfloat ...ivate/bigfloat/mpfr.rkt:385:0 -------------------------------------------------------------------- map [44] 1.8% for-loop [86] 3.1% loop [48] 95.2% [88] 43650(79.7%) 708(1.3%) ??? ...tlies/herbie/develop/src/programs.rkt:155:4 ??? [89] 90.8% ??? [90] 6.7% ->flonum [91] 0.8% -------------------------------------------------------------------- ??? [88] 100.0% [89] 39654(72.4%) 6128(11.2%) ??? ...acket/collects/racket/private/kw.rkt:444:14 ??? [92] 82.8% -------------------------------------------------------------------- ??? [88] 100.0% [90] 2926(5.3%) 0(0.0%) ??? ...contract/private/../../private/kw.rkt:761:9 real->bigfloat [93] 100.0% -------------------------------------------------------------------- for-loop [86] 19.5% ??? [88] 21.1% loop [48] 59.4% [91] 1716(3.1%) 660(1.2%) ->flonum ...s/herbie/develop/src/programs.rkt:37:0 ??? [94] 42.1% return/no-unsupplied [95] 19.5% -------------------------------------------------------------------- loop [48] 2.0% ??? [89] 98.0% [92] 33490(61.2%) 26424(48.3%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfsub [96] 11.0% bflog [97] 10.1% -------------------------------------------------------------------- ??? [90] 100.0% [93] 2926(5.3%) 2208(4.0%) real->bigfloat .../private/bigfloat/mpfr.rkt:451:0 ??? [99] 24.5% -------------------------------------------------------------------- ->flonum [91] 100.0% [94] 722(1.3%) 360(0.7%) ??? ...racket/collects/racket/private/kw.rkt:761:9 ??? [98] 50.1% -------------------------------------------------------------------- ->flonum [91] 100.0% [95] 334(0.6%) 334(0.6%) return/no-unsupplied ...ct/private/arr-i.rkt:533:0 -------------------------------------------------------------------- ??? [92] 100.0% [96] 3690(6.7%) 1918(3.5%) bfsub ...-lib/math/private/bigfloat/mpfr.rkt:819:4 ??? [99] 48.0% -------------------------------------------------------------------- ??? [92] 100.0% [97] 3376(6.2%) 2682(4.9%) bflog ...-lib/math/private/bigfloat/mpfr.rkt:616:4 ??? [99] 20.6% -------------------------------------------------------------------- ??? [94] 100.0% [98] 362(0.7%) 362(0.7%) ??? ...s/racket/contract/private/arr-i.rkt:1233:27 -------------------------------------------------------------------- bflog [97] 21.8% real->bigfloat [93] 22.6% bfsub [96] 55.7% [99] 3184(5.8%) 3184(5.8%) ??? .../share/racket/collects/ffi/unsafe.rkt:861:4 --------------------------------------------------------------------