Profiling results ----------------- Total cpu time observed: 33190ms (out of 33068ms) Number of samples taken: 140 (once every 237ms) ======================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ======================================================================== run [8] 50.0% [1] 33190(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [5] 50.0% loop [9] 46.6% run-improve50 [6] 3.4% ------------------------------------------------------------------------ hash-ref! [46] 100.0% [2] 1478(4.5%) 0(0.0%) ??? /home/ztatlock/herbie/src/programs.rkt:181:5 loop [40] 100.0% ------------------------------------------------------------------------ loop [40] 100.0% [3] 178(0.5%) 0(0.0%) ??? ...ket/collects/racket/private/list.rkt:323:18 hash-ref! [46] 100.0% ------------------------------------------------------------------------ loop [40] 100.0% [4] 176(0.5%) 0(0.0%) for-loop ...ock/herbie/src/core/simplify.rkt:161:2 eval-const-expr [7] 100.0% ------------------------------------------------------------------------ ??? [1] 100.0% [5] 33190(100.0%) 0(0.0%) profile-thunk16 ...t/pkgs/profile-lib/main.rkt:9:0 run [8] 100.0% ------------------------------------------------------------------------ ??? [1] 100.0% [6] 2282(6.9%) 0(0.0%) run-improve50 ...ock/herbie/src/mainloop.rkt:234:0 get-final-combination [10] 35.1% loop [9] 31.1% setup-alt-simplified [11] 25.9% for-loop [12] 7.8% ------------------------------------------------------------------------ for-loop [4] 100.0% [7] 176(0.5%) 0(0.0%) eval-const-expr ...k/herbie/src/programs.rkt:164:0 f134 [30] 100.0% ------------------------------------------------------------------------ profile-thunk16 [5] 100.0% [8] 33190(100.0%) 0(0.0%) run ...share/racket/pkgs/profile-lib/main.rkt:39:2 ??? [1] 100.0% ------------------------------------------------------------------------ run-improve50 [6] 2.2% ??? [1] 97.8% [9] 31618(95.3%) 0(0.0%) loop /home/ztatlock/herbie/src/points.rkt:159:2 make-exacts* [19] 41.3% loop [13] 35.1% loop [20] 15.8% loop [14] 6.8% for-loop [17] 1.0% ------------------------------------------------------------------------ run-improve50 [6] 100.0% [10] 802(2.4%) 0(0.0%) get-final-combination ...ie/src/mainloop.rkt:264:0 extract-alt [15] 100.0% ------------------------------------------------------------------------ run-improve50 [6] 100.0% [11] 592(1.8%) 0(0.0%) setup-alt-simplified ...k/herbie/src/glue.rkt:54:0 simplify-alt [16] 100.0% ------------------------------------------------------------------------ run-improve50 [6] 100.0% [12] 178(0.5%) 0(0.0%) for-loop ...tatlock/herbie/src/mainloop.rkt:249:10 run-iter! [18] 100.0% ------------------------------------------------------------------------ loop [9] 100.0% [13] 11088(33.4%) 0(0.0%) loop /home/ztatlock/herbie/src/points.rkt:119:2 loop [20] 51.6% make-exacts* [19] 42.9% eval-prog [25] 5.5% ------------------------------------------------------------------------ loop [9] 0.1% loop [14] 99.9% [14] 2152(6.5%) 2152(6.5%) loop ...hare/racket/collects/racket/list.rkt:154:2 loop [14] 99.9% ------------------------------------------------------------------------ get-final-combination [10] 100.0% [15] 802(2.4%) 0(0.0%) extract-alt ...e/ztatlock/herbie/src/glue.rkt:60:0 loop [40] 80.5% argmins [24] 19.5% ------------------------------------------------------------------------ setup-alt-simplified [11] 100.0% [16] 592(1.8%) 0(0.0%) simplify-alt .../ztatlock/herbie/src/glue.rkt:87:0 simplify [21] 100.0% ------------------------------------------------------------------------ loop [9] 100.0% [17] 320(1.0%) 0(0.0%) for-loop .../ztatlock/herbie/src/points.rkt:166:24 for-loop [22] 100.0% ------------------------------------------------------------------------ for-loop [12] 100.0% [18] 178(0.5%) 0(0.0%) run-iter! ...tatlock/herbie/src/mainloop.rkt:215:0 localize-error [23] 100.0% ------------------------------------------------------------------------ loop [13] 26.7% loop [9] 73.3% [19] 17828(53.7%) 0(0.0%) make-exacts* ...atlock/herbie/src/points.rkt:106:0 map [34] 51.4% loop [40] 39.0% eval-prog [25] 9.6% ------------------------------------------------------------------------ loop [9] 46.6% loop [13] 53.4% [20] 10710(32.3%) 0(0.0%) loop /home/ztatlock/herbie/src/points.rkt:109:4 map [34] 100.0% ------------------------------------------------------------------------ simplify-alt [16] 47.8% loop [40] 52.2% [21] 1238(3.7%) 0(0.0%) simplify ...lock/herbie/src/core/simplify.rkt:39:0 iterate-egraph!5 [26] 100.0% ------------------------------------------------------------------------ for-loop [17] 100.0% [22] 320(1.0%) 0(0.0%) for-loop .../ztatlock/herbie/src/points.rkt:167:26 ??? [27] 100.0% ------------------------------------------------------------------------ run-iter! [18] 100.0% [23] 178(0.5%) 0(0.0%) localize-error ...erbie/src/core/localize.rkt:47:0 hash-ref! [46] 100.0% ------------------------------------------------------------------------ extract-alt [15] 100.0% [24] 156(0.5%) 0(0.0%) argmins /home/ztatlock/herbie/src/common.rkt:134:0 composed [28] 100.0% ------------------------------------------------------------------------ loop [13] 26.1% make-exacts* [19] 73.9% [25] 2326(7.0%) 148(0.4%) eval-prog ...tatlock/herbie/src/programs.rkt:146:0 hash-ref! [46] 63.5% f134 [30] 30.1% ------------------------------------------------------------------------ simplify [21] 100.0% [26] 1238(3.7%) 0(0.0%) iterate-egraph!5 ...bie/src/core/simplify.rkt:95:0 one-iter [29] 85.8% map-enodes [31] 14.2% ------------------------------------------------------------------------ for-loop [22] 100.0% [27] 320(1.0%) 170(0.5%) ??? /home/ztatlock/herbie/src/points.rkt:156:9 random-exp [33] 46.9% ------------------------------------------------------------------------ argmins [24] 100.0% [28] 156(0.5%) 0(0.0%) composed ...ollects/racket/private/list.rkt:321:16 errors [32] 100.0% ------------------------------------------------------------------------ iterate-egraph!5 [26] 100.0% [29] 1062(3.2%) 0(0.0%) one-iter ...ock/herbie/src/core/simplify.rkt:106:0 for-loop [35] 68.2% loop [37] 16.8% apply-match [41] 15.1% ------------------------------------------------------------------------ eval-const-expr [7] 16.7% hash-ref! [46] 16.9% f134 [30] 33.2% eval-prog [25] 33.2% [30] 1054(3.2%) 302(0.9%) f134 .../collects/racket/match/compiler.rkt:507:40 f134 [30] 33.2% ??? [36] 18.9% map [34] 16.9% ...erbie/src/common.rkt:46:2 [38] 16.7% ------------------------------------------------------------------------ iterate-egraph!5 [26] 100.0% [31] 176(0.5%) 0(0.0%) map-enodes ...ock/herbie/src/core/egraph.rkt:139:0 loop [40] 100.0% ------------------------------------------------------------------------ composed [28] 100.0% [32] 156(0.5%) 0(0.0%) errors /home/ztatlock/herbie/src/points.rkt:184:0 for-loop [39] 100.0% ------------------------------------------------------------------------ ??? [27] 100.0% [33] 150(0.5%) 150(0.5%) random-exp ...ztatlock/herbie/src/common.rkt:222:0 ------------------------------------------------------------------------ f134 [30] 0.9% make-exacts* [19] 45.7% loop [20] 53.4% [34] 20050(60.4%) 0(0.0%) map ...acket/collects/racket/private/map.rkt:20:13 loop [40] 100.0% ------------------------------------------------------------------------ for-loop [35] 50.0% one-iter [29] 50.0% [35] 724(2.2%) 0(0.0%) for-loop ...ck/herbie/src/core/simplify.rkt:116:12 for-loop [35] 50.0% match-e [48] 50.0% ------------------------------------------------------------------------ f134 [30] 100.0% [36] 398(1.2%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:342:33 ??? [42] 68.8% table-ref [44] 31.2% ------------------------------------------------------------------------ one-iter [29] 100.0% [37] 178(0.5%) 0(0.0%) loop ...cket/collects/racket/private/map.rkt:52:19 apply-match [41] 100.0% ------------------------------------------------------------------------ f134 [30] 100.0% [38] 176(0.5%) 0(0.0%) ...erbie/src/common.rkt:46:2 ...e/arr-i.rkt:906:19 ??? [43] 100.0% ------------------------------------------------------------------------ errors [32] 100.0% [39] 156(0.5%) 0(0.0%) for-loop ...e/ztatlock/herbie/src/points.rkt:187:4 ??? [45] 100.0% ------------------------------------------------------------------------ parse-loop12 [60] 0.0% map-enodes [31] 0.1% make-exacts* [19] 0.8% extract-alt [15] 2.2% map [34] 2.3% ??? [2] 5.0% loop [40] 89.5% [40] 29298(88.3%) 0(0.0%) loop ...cket/collects/racket/private/map.rkt:25:19 loop [40] 89.5% hash-ref! [46] 5.0% ??? [45] 3.0% simplify [21] 2.2% for-loop [4] 0.1% ->flonum [47] 0.0% ??? [3] 0.0% ??? [73] 0.0% ------------------------------------------------------------------------ one-iter [29] 47.3% loop [37] 52.7% [41] 338(1.0%) 0(0.0%) apply-match .../herbie/src/core/simplify.rkt:122:2 merge-egraph-nodes! [50] 52.7% match-e [48] 47.3% ------------------------------------------------------------------------ ??? [36] 100.0% [42] 274(0.8%) 0(0.0%) ??? ...ects/racket/contract/private/guts.rkt:644:8 ??? [49] 100.0% ------------------------------------------------------------------------ ??? [51] 50.0% ...erbie/src/common.rkt:46:2 [38] 50.0% [43] 176(0.5%) 176(0.5%) ??? ...ects/racket/contract/private/list.rkt:342:4 ??? [51] 50.0% ------------------------------------------------------------------------ ??? [36] 100.0% [44] 124(0.4%) 0(0.0%) table-ref .../racket/private/norm-define.rkt:53:83 ??? [52] 100.0% ------------------------------------------------------------------------ for-loop [39] 0.6% loop [40] 99.4% [45] 25644(77.3%) 1486(4.5%) ??? /home/ztatlock/herbie/src/programs.rkt:152:4 ??? [53] 74.5% ??? [54] 19.7% ------------------------------------------------------------------------ localize-error [23] 3.6% ??? [3] 7.2% eval-prog [25] 29.8% loop [40] 59.5% [46] 1656(5.0%) 0(0.0%) hash-ref! ...racket/private/more-scheme.rkt:373:13 ??? [2] 59.5% bigfloat-hash [55] 29.8% parse-loop12 [60] 7.2% f134 [30] 3.6% ------------------------------------------------------------------------ loop [40] 100.0% [47] 1332(4.0%) 178(0.5%) ->flonum .../ztatlock/herbie/src/programs.rkt:34:0 ??? [56] 86.6% ------------------------------------------------------------------------ apply-match [41] 18.1% for-loop [35] 81.9% [48] 884(2.7%) 566(1.7%) match-e ...tatlock/herbie/src/core/ematch.rkt:46:0 for-loop [57] 36.0% ------------------------------------------------------------------------ ??? [42] 100.0% [49] 274(0.8%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:357:18 hash-has-key? [58] 100.0% ------------------------------------------------------------------------ apply-match [41] 100.0% [50] 178(0.5%) 0(0.0%) merge-egraph-nodes! ...e/src/core/egraph.rkt:152:0 update-leader! [59] 100.0% ------------------------------------------------------------------------ ??? [43] 100.0% [51] 176(0.5%) 0(0.0%) ??? ...cts/racket/contract/private/list.rkt:187:10 ??? [43] 100.0% ------------------------------------------------------------------------ table-ref [44] 100.0% [52] 124(0.4%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:737:4 for-loop [61] 100.0% ------------------------------------------------------------------------ ??? [45] 100.0% [53] 19108(57.6%) 4240(12.8%) ??? ...acket/collects/racket/private/kw.rkt:444:14 ??? [73] 55.7% bf* [63] 18.2% ??? [65] 2.2% ??? [66] 1.6% ------------------------------------------------------------------------ ??? [45] 100.0% [54] 5050(15.2%) 0(0.0%) ??? ...contract/private/../../private/kw.rkt:761:9 real->bigfloat [62] 100.0% ------------------------------------------------------------------------ hash-ref! [46] 100.0% [55] 1478(4.5%) 0(0.0%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:165:0 bfcanonicalize [64] 100.0% ------------------------------------------------------------------------ ->flonum [47] 100.0% [56] 1154(3.5%) 800(2.4%) ??? /home/ztatlock/herbie/src/config.rkt:48:0 ??? [68] 15.4% return/no-unsupplied [69] 15.3% ------------------------------------------------------------------------ match-e [48] 100.0% [57] 318(1.0%) 0(0.0%) for-loop ...tlock/herbie/src/core/ematch.rkt:59:11 for-loop [70] 50.3% list-cartesian-product [71] 49.7% ------------------------------------------------------------------------ ??? [49] 100.0% [58] 274(0.8%) 274(0.8%) hash-has-key? ...et/private/more-scheme.rkt:368:13 ------------------------------------------------------------------------ merge-egraph-nodes! [50] 100.0% [59] 178(0.5%) 0(0.0%) update-leader! ...herbie/src/core/egraph.rkt:217:0 make-sequence [67] 100.0% ------------------------------------------------------------------------ hash-ref! [46] 100.0% [60] 178(0.5%) 0(0.0%) parse-loop12 ...s/racket/match/compiler.rkt:418:15 loop [40] 100.0% ------------------------------------------------------------------------ ??? [52] 100.0% [61] 124(0.4%) 0(0.0%) for-loop ...acket/contract/private/list.rkt:743:12 ??? [72] 100.0% ------------------------------------------------------------------------ ??? [54] 100.0% [62] 5050(15.2%) 3072(9.3%) real->bigfloat .../private/bigfloat/mpfr.rkt:451:0 ??? [83] 39.2% ------------------------------------------------------------------------ ??? [53] 100.0% [63] 3484(10.5%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [73] 100.0% ------------------------------------------------------------------------ bigfloat-hash [55] 100.0% [64] 1478(4.5%) 1320(4.0%) bfcanonicalize .../private/bigfloat/mpfr.rkt:155:0 bigfloat->sig+exp [77] 10.7% ------------------------------------------------------------------------ ??? [53] 100.0% [65] 422(1.3%) 422(1.3%) ??? ...rivate/unconstrained-domain-arrow.rkt:40:13 ------------------------------------------------------------------------ ??? [53] 100.0% [66] 314(0.9%) 192(0.6%) ??? ...ects/racket/contract/private/prop.rkt:493:4 ??? [79] 38.9% ------------------------------------------------------------------------ update-leader! [59] 100.0% [67] 178(0.5%) 178(0.5%) make-sequence ...ects/racket/private/for.rkt:509:2 ------------------------------------------------------------------------ ??? [56] 100.0% [68] 178(0.5%) 0(0.0%) ??? ...racket/collects/racket/private/kw.rkt:761:9 ??? [74] 100.0% ------------------------------------------------------------------------ ??? [56] 100.0% [69] 176(0.5%) 0(0.0%) return/no-unsupplied ...ct/private/arr-i.rkt:533:0 filter [75] 100.0% ------------------------------------------------------------------------ for-loop [57] 100.0% [70] 160(0.5%) 0(0.0%) for-loop ...tlock/herbie/src/core/ematch.rkt:65:37 ??? [76] 100.0% ------------------------------------------------------------------------ for-loop [57] 100.0% [71] 158(0.5%) 158(0.5%) list-cartesian-product ...src/core/ematch.rkt:20:0 ------------------------------------------------------------------------ for-loop [61] 100.0% [72] 124(0.4%) 0(0.0%) ??? ...private/unconstrained-domain-arrow.rkt:74:4 make-keyword-procedure [78] 100.0% ------------------------------------------------------------------------ loop [40] 1.2% bf* [63] 24.3% ??? [53] 74.4% [73] 14310(43.1%) 4696(14.1%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfexp [80] 34.1% bfmul [81] 33.1% ------------------------------------------------------------------------ ??? [68] 100.0% [74] 178(0.5%) 178(0.5%) ??? ...s/racket/contract/private/arr-i.rkt:1233:27 ------------------------------------------------------------------------ return/no-unsupplied [69] 100.0% [75] 176(0.5%) 176(0.5%) filter ...t/collects/racket/private/list.rkt:256:2 ------------------------------------------------------------------------ for-loop [70] 100.0% [76] 160(0.5%) 0(0.0%) ??? /home/ztatlock/herbie/src/core/ematch.rkt:50:5 for-loop [82] 100.0% ------------------------------------------------------------------------ bfcanonicalize [64] 100.0% [77] 158(0.5%) 158(0.5%) bigfloat->sig+exp ...ivate/bigfloat/mpfr.rkt:370:0 ------------------------------------------------------------------------ ??? [72] 100.0% [78] 124(0.4%) 124(0.4%) make-keyword-procedure ...ket/private/kw.rkt:260:4 ------------------------------------------------------------------------ ??? [66] 100.0% [79] 122(0.4%) 122(0.4%) ??? ...lects/racket/contract/private/orc.rkt:83:14 ------------------------------------------------------------------------ ??? [73] 100.0% [80] 4876(14.7%) 3386(10.2%) bfexp ...-lib/math/private/bigfloat/mpfr.rkt:616:4 ??? [83] 30.6% ------------------------------------------------------------------------ ??? [73] 100.0% [81] 4738(14.3%) 3064(9.2%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:819:4 ??? [83] 35.3% ------------------------------------------------------------------------ ??? [76] 100.0% [82] 160(0.5%) 160(0.5%) for-loop ...atlock/herbie/src/core/ematch.rkt:51:7 ------------------------------------------------------------------------ bfexp [80] 29.0% bfmul [81] 32.6% real->bigfloat [62] 38.5% [83] 5142(15.5%) 5142(15.5%) ??? .../share/racket/collects/ffi/unsafe.rkt:861:4 ------------------------------------------------------------------------