Profiling results ----------------- Total cpu time observed: 13032ms (out of 13228ms) Number of samples taken: 117 (once every 111ms) ======================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ======================================================================== ??? [6] 100.0% [1] 12782(98.1%) 0(0.0%) profile-thunk16 ...t/pkgs/profile-lib/main.rkt:9:0 run [4] 100.0% ------------------------------------------------------------------------ match-e [37] 100.0% [2] 316(2.4%) 84(0.6%) for-loop ...plex-support/src/core/ematch.rkt:59:11 for-loop [5] 86.7% ------------------------------------------------------------------------ loop [30] 100.0% [3] 84(0.6%) 0(0.0%) ??? ...ket/collects/racket/private/list.rkt:323:18 hash-ref! [26] 100.0% ------------------------------------------------------------------------ profile-thunk16 [1] 98.7% [4] 12956(99.4%) 0(0.0%) run ...share/racket/pkgs/profile-lib/main.rkt:39:2 ??? [6] 100.0% ------------------------------------------------------------------------ for-loop [2] 100.0% [5] 316(2.4%) 0(0.0%) for-loop ...plex-support/src/core/ematch.rkt:65:37 match-e [37] 100.0% ------------------------------------------------------------------------ run [4] 50.4% [6] 13032(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 49.0% loop [8] 43.5% run-improve50 [7] 7.5% ------------------------------------------------------------------------ ??? [6] 100.0% [7] 1868(14.3%) 0(0.0%) run-improve50 ...ex-support/src/mainloop.rkt:235:0 setup-alt-simplified [9] 33.3% get-final-combination [10] 29.0% loop [8] 24.2% for-loop [11] 8.6% setup-prog [12] 4.9% ------------------------------------------------------------------------ run-improve50 [7] 3.9% ??? [6] 96.1% [8] 11616(89.1%) 0(0.0%) loop ...erbie/complex-support/src/points.rkt:159:2 loop [13] 59.7% make-exacts* [18] 38.9% for-loop [16] 1.4% ------------------------------------------------------------------------ run-improve50 [7] 100.0% [9] 622(4.8%) 0(0.0%) setup-alt-simplified ...-support/src/glue.rkt:55:0 simplify-alt [14] 100.0% ------------------------------------------------------------------------ run-improve50 [7] 100.0% [10] 542(4.2%) 0(0.0%) get-final-combination ...rt/src/mainloop.rkt:265:0 extract-alt [15] 100.0% ------------------------------------------------------------------------ run-improve50 [7] 100.0% [11] 160(1.2%) 0(0.0%) for-loop ...omplex-support/src/mainloop.rkt:250:10 run-iter! [17] 100.0% ------------------------------------------------------------------------ run-improve50 [7] 100.0% [12] 92(0.7%) 0(0.0%) setup-prog ...ie/complex-support/src/glue.rkt:46:0 ??? [39] 100.0% ------------------------------------------------------------------------ loop [8] 100.0% [13] 6934(53.2%) 0(0.0%) loop ...erbie/complex-support/src/points.rkt:119:2 loop [19] 75.2% make-exacts* [18] 17.0% eval-prog [24] 7.8% ------------------------------------------------------------------------ setup-alt-simplified [9] 100.0% [14] 622(4.8%) 0(0.0%) simplify-alt .../complex-support/src/glue.rkt:88:0 simplify [31] 100.0% ------------------------------------------------------------------------ get-final-combination [10] 100.0% [15] 542(4.2%) 0(0.0%) extract-alt ...e/complex-support/src/glue.rkt:61:0 loop [30] 100.0% ------------------------------------------------------------------------ loop [8] 100.0% [16] 160(1.2%) 0(0.0%) for-loop .../complex-support/src/points.rkt:166:24 for-loop [20] 100.0% ------------------------------------------------------------------------ for-loop [11] 100.0% [17] 160(1.2%) 0(0.0%) run-iter! ...omplex-support/src/mainloop.rkt:216:0 localize-error [21] 52.5% choose-best-alt! [22] 47.5% ------------------------------------------------------------------------ loop [13] 20.7% loop [8] 79.3% [18] 5702(43.8%) 96(0.7%) make-exacts* ...mplex-support/src/points.rkt:106:0 map [23] 52.1% loop [30] 44.7% eval-prog [24] 1.5% ------------------------------------------------------------------------ loop [13] 100.0% [19] 5216(40.0%) 0(0.0%) loop ...erbie/complex-support/src/points.rkt:109:4 map [23] 51.4% loop [30] 48.6% ------------------------------------------------------------------------ for-loop [16] 100.0% [20] 160(1.2%) 0(0.0%) for-loop .../complex-support/src/points.rkt:167:26 ??? [25] 100.0% ------------------------------------------------------------------------ run-iter! [17] 100.0% [21] 84(0.6%) 0(0.0%) localize-error ...pport/src/core/localize.rkt:47:0 hash-ref! [26] 100.0% ------------------------------------------------------------------------ run-iter! [17] 100.0% [22] 76(0.6%) 0(0.0%) choose-best-alt! ...support/src/mainloop.rkt:116:0 ??? [39] 100.0% ------------------------------------------------------------------------ loop [19] 47.4% make-exacts* [18] 52.6% [23] 5650(43.4%) 0(0.0%) map ...acket/collects/racket/private/map.rkt:20:13 loop [30] 100.0% ------------------------------------------------------------------------ make-exacts* [18] 14.1% loop [13] 85.9% [24] 626(4.8%) 278(2.1%) eval-prog ...omplex-support/src/programs.rkt:145:0 [running expand-time body] [27] 32.6% ??? [44] 23.0% ------------------------------------------------------------------------ for-loop [20] 100.0% [25] 160(1.2%) 82(0.6%) ??? ...herbie/complex-support/src/points.rkt:156:9 random-exp [29] 48.8% ------------------------------------------------------------------------ ??? [3] 50.0% localize-error [21] 50.0% [26] 84(0.6%) 0(0.0%) hash-ref! ...racket/private/more-scheme.rkt:373:13 parse-loop12 [28] 100.0% ------------------------------------------------------------------------ eval-prog [24] 100.0% [27] 204(1.6%) 204(1.6%) [running expand-time body] ...m/flonum-exp.rkt:##f ------------------------------------------------------------------------ hash-ref! [26] 100.0% [28] 84(0.6%) 0(0.0%) parse-loop12 ...s/racket/match/compiler.rkt:418:15 loop [30] 100.0% ------------------------------------------------------------------------ ??? [25] 100.0% [29] 78(0.6%) 78(0.6%) random-exp ...complex-support/src/common.rkt:226:0 ------------------------------------------------------------------------ make-exacts* [18] 0.0% parse-loop12 [28] 0.0% loop [19] 0.2% map [23] 0.4% extract-alt [15] 4.8% loop [30] 94.6% [30] 11360(87.2%) 0(0.0%) loop ...cket/collects/racket/private/map.rkt:25:19 loop [30] 94.6% simplify [31] 4.8% ??? [67] 0.5% ??? [44] 0.0% ??? [3] 0.0% ...higher-order.rkt:346:33 [74] 0.0% ------------------------------------------------------------------------ loop [30] 46.6% simplify-alt [14] 53.4% [31] 1164(8.9%) 0(0.0%) simplify ...lex-support/src/core/simplify.rkt:39:0 iterate-egraph!5 [32] 100.0% ------------------------------------------------------------------------ simplify [31] 100.0% [32] 1164(8.9%) 0(0.0%) iterate-egraph!5 ...ort/src/core/simplify.rkt:95:0 one-iter [33] 100.0% ------------------------------------------------------------------------ iterate-egraph!5 [32] 100.0% [33] 1164(8.9%) 0(0.0%) one-iter ...ex-support/src/core/simplify.rkt:106:0 loop [34] 55.8% for-loop [35] 38.7% apply-match [36] 5.5% ------------------------------------------------------------------------ one-iter [33] 100.0% [34] 650(5.0%) 0(0.0%) loop ...cket/collects/racket/private/map.rkt:52:19 apply-match [36] 100.0% ------------------------------------------------------------------------ for-loop [35] 50.0% one-iter [33] 50.0% [35] 450(3.5%) 0(0.0%) for-loop ...x-support/src/core/simplify.rkt:116:12 for-loop [35] 50.0% match-e [37] 50.0% ------------------------------------------------------------------------ one-iter [33] 9.0% loop [34] 91.0% [36] 714(5.5%) 0(0.0%) apply-match ...support/src/core/simplify.rkt:123:2 match-e [37] 35.9% for-loop [38] 21.8% mk-enode! [41] 21.6% substitute-e [40] 20.7% ------------------------------------------------------------------------ apply-match [36] 16.0% for-loop [5] 24.5% for-loop [35] 59.5% [37] 706(5.4%) 444(3.4%) match-e ...omplex-support/src/core/ematch.rkt:46:0 for-loop [2] 30.5% ??? [39] 16.7% ------------------------------------------------------------------------ apply-match [36] 100.0% [38] 156(1.2%) 0(0.0%) for-loop ...x-support/src/core/simplify.rkt:137:10 substitute-e [40] 100.0% ------------------------------------------------------------------------ choose-best-alt! [22] 22.0% setup-prog [12] 26.6% match-e [37] 51.4% [39] 346(2.7%) 182(1.4%) ??? ...contract/private/arrow-val-first.rkt:357:18 hash-has-key? [42] 25.4% ??? [43] 22.0% ------------------------------------------------------------------------ apply-match [36] 48.7% for-loop [38] 51.3% [40] 304(2.3%) 0(0.0%) substitute-e ...x-support/src/core/ematch.rkt:71:0 mk-enode! [41] 100.0% ------------------------------------------------------------------------ apply-match [36] 33.6% substitute-e [40] 66.4% [41] 458(3.5%) 0(0.0%) mk-enode! ...lex-support/src/core/egraph.rkt:101:0 new-enode [45] 100.0% ------------------------------------------------------------------------ ??? [39] 100.0% [42] 88(0.7%) 0(0.0%) hash-has-key? ...et/private/more-scheme.rkt:368:13 ??? [46] 100.0% ------------------------------------------------------------------------ ??? [39] 100.0% [43] 76(0.6%) 0(0.0%) ??? ...complex-support/src/core/alt-table.rkt:54:0 ??? [44] 100.0% ------------------------------------------------------------------------ ??? [43] 7.6% eval-prog [24] 14.5% loop [30] 77.9% [44] 996(7.6%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:342:33 ->flonum [47] 77.9% ??? [49] 14.5% best-alt [51] 7.6% ------------------------------------------------------------------------ mk-enode! [41] 100.0% [45] 458(3.5%) 0(0.0%) new-enode ...mplex-support/src/core/enode.rkt:97:0 type-of-enode-expr [48] 100.0% ------------------------------------------------------------------------ hash-has-key? [42] 100.0% [46] 88(0.7%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:737:4 for-loop [50] 100.0% ------------------------------------------------------------------------ ??? [44] 100.0% [47] 776(6.0%) 200(1.5%) ->flonum ...s/racket/private/norm-define.rkt:53:83 ??? [52] 64.7% ??? [56] 9.5% ------------------------------------------------------------------------ new-enode [45] 100.0% [48] 458(3.5%) 0(0.0%) type-of-enode-expr ...port/src/core/enode.rkt:68:0 get-sigs [53] 100.0% ------------------------------------------------------------------------ ??? [44] 100.0% [49] 144(1.1%) 144(1.1%) ??? ...tract/private/arrow-higher-order.rkt:336:46 ------------------------------------------------------------------------ ??? [46] 100.0% [50] 88(0.7%) 0(0.0%) for-loop ...acket/contract/private/list.rkt:743:12 arrow-higher-order:lnp [54] 100.0% ------------------------------------------------------------------------ ??? [44] 100.0% [51] 76(0.6%) 0(0.0%) best-alt ...rbie/complex-support/src/glue.rkt:83:0 argmins [55] 100.0% ------------------------------------------------------------------------ ->flonum [47] 100.0% [52] 502(3.9%) 338(2.6%) ??? .../herbie/complex-support/src/config.rkt:48:0 ??? [58] 32.7% ------------------------------------------------------------------------ type-of-enode-expr [48] 100.0% [53] 458(3.5%) 0(0.0%) get-sigs ...complex-support/src/type-check.rkt:5:0 ??? [57] 81.7% ...pport/src/common.rkt:46:2 [60] 18.3% ------------------------------------------------------------------------ for-loop [50] 100.0% [54] 88(0.7%) 0(0.0%) arrow-higher-order:lnp ...w-higher-order.rkt:597:7 ??? [59] 100.0% ------------------------------------------------------------------------ best-alt [51] 100.0% [55] 76(0.6%) 0(0.0%) argmins ...ie/complex-support/src/common.rkt:138:0 composed [61] 100.0% ------------------------------------------------------------------------ ->flonum [47] 100.0% [56] 74(0.6%) 0(0.0%) ??? ...-racket/utils/simple-result-arrow.rkt:60:15 ??? [77] 100.0% ------------------------------------------------------------------------ get-sigs [53] 100.0% [57] 374(2.9%) 0(0.0%) ??? ...ts/racket/contract/private/arr-i.rkt:804:10 for-loop [62] 100.0% ------------------------------------------------------------------------ ??? [52] 100.0% [58] 164(1.3%) 76(0.6%) ??? ...racket/collects/racket/private/kw.rkt:761:9 ??? [63] 53.7% ------------------------------------------------------------------------ arrow-higher-order:lnp [54] 100.0% [59] 88(0.7%) 88(0.7%) ??? ...et/contract/private/arity-checking.rkt:19:2 ------------------------------------------------------------------------ get-sigs [53] 100.0% [60] 84(0.6%) 0(0.0%) ...pport/src/common.rkt:46:2 ...e/arr-i.rkt:906:19 ??? [64] 100.0% ------------------------------------------------------------------------ argmins [55] 100.0% [61] 76(0.6%) 0(0.0%) composed ...ollects/racket/private/list.rkt:321:16 errors [65] 100.0% ------------------------------------------------------------------------ ??? [57] 100.0% [62] 374(2.9%) 0(0.0%) for-loop ...racket/contract/private/hash.rkt:239:6 ??? [76] 100.0% ------------------------------------------------------------------------ ??? [58] 100.0% [63] 88(0.7%) 88(0.7%) ??? ...s/racket/contract/private/arr-i.rkt:1233:27 ------------------------------------------------------------------------ ...pport/src/common.rkt:46:2 [60]100.0% [64] 84(0.6%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:342:4 ??? [76] 100.0% ------------------------------------------------------------------------ composed [61] 100.0% [65] 76(0.6%) 0(0.0%) errors ...bie/complex-support/src/points.rkt:184:0 for-loop [66] 100.0% ------------------------------------------------------------------------ errors [65] 100.0% [66] 76(0.6%) 0(0.0%) for-loop ...e/complex-support/src/points.rkt:187:4 ??? [67] 100.0% ------------------------------------------------------------------------ for-loop [66] 0.8% loop [30] 99.2% [67] 10034(77.0%) 632(4.8%) ??? ...rbie/complex-support/src/programs.rkt:151:4 ??? [68] 58.4% ??? [69] 34.3% ??? [70] 0.9% ------------------------------------------------------------------------ ??? [67] 100.0% [68] 5862(45.0%) 1218(9.3%) ??? ...acket/collects/racket/private/kw.rkt:444:14 ??? [71] 72.4% ??? [73] 5.2% ...higher-order.rkt:346:33 [74] 1.6% ------------------------------------------------------------------------ ??? [67] 100.0% [69] 3446(26.4%) 0(0.0%) ??? ...contract/private/../../private/kw.rkt:761:9 real->bigfloat [72] 100.0% ------------------------------------------------------------------------ ??? [67] 100.0% [70] 94(0.7%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:145:21 ??? [79] 100.0% ------------------------------------------------------------------------ ??? [68] 100.0% [71] 4242(32.6%) 166(1.3%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfadd [75] 96.1% ------------------------------------------------------------------------ ??? [69] 100.0% [72] 3446(26.4%) 2536(19.5%) real->bigfloat .../private/bigfloat/mpfr.rkt:451:0 ??? [77] 26.4% ------------------------------------------------------------------------ ??? [68] 100.0% [73] 306(2.3%) 306(2.3%) ??? ...rivate/unconstrained-domain-arrow.rkt:40:13 ------------------------------------------------------------------------ loop [30] 46.7% ??? [68] 53.3% [74] 180(1.4%) 0(0.0%) ...higher-order.rkt:346:33 (unknown source) ??? [76] 100.0% ------------------------------------------------------------------------ ??? [71] 100.0% [75] 4076(31.3%) 3598(27.6%) bfadd ...-lib/math/private/bigfloat/mpfr.rkt:819:4 ??? [77] 11.7% ------------------------------------------------------------------------ ??? [64] 13.2% ...higher-order.rkt:346:33 [74] 28.2% for-loop [62] 58.6% [76] 638(4.9%) 264(2.0%) ??? ...cts/racket/contract/private/list.rkt:187:10 ??? [78] 58.6% ------------------------------------------------------------------------ ??? [56] 5.1% bfadd [75] 32.7% real->bigfloat [72] 62.2% [77] 1462(11.2%) 1462(11.2%) ??? .../share/racket/collects/ffi/unsafe.rkt:861:4 ------------------------------------------------------------------------ ??? [76] 100.0% [78] 374(2.9%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:686:7 ??? [79] 100.0% ------------------------------------------------------------------------ ??? [70] 20.1% ??? [78] 79.9% [79] 468(3.6%) 468(3.6%) ??? ...ects/racket/contract/private/guts.rkt:644:8 ------------------------------------------------------------------------