Profiling results ----------------- Total cpu time observed: 141816ms (out of 143532ms) Number of samples taken: 410 (once every 346ms) (Hiding functions with self<1.0% and local<2.0%: 9 of 193 hidden) ============================================================================ Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ============================================================================ ??? [72] 100.0% [1] 141816(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [9] 100.0% ---------------------------------------------------------------------------- ??? [72] 100.0% [2] 93398(65.9%) 0(0.0%) run-improve40 ...ie/develop/src/mainloop.rkt:336:0 for-loop [10] 99.0% loop [82] 0.3% ??? [41] 0.3% simplify! [15] 0.3% ---------------------------------------------------------------------------- loop [113] 100.0% [3] 7870(5.5%) 0(0.0%) composed ...ollects/racket/private/list.rkt:321:16 ??? [140] 100.0% ---------------------------------------------------------------------------- substitute-e [70] 9.5% match-e [61] 90.5% [4] 3126(2.2%) 298(0.2%) variable? .../develop/src/syntax/syntax.rkt:1159:0 ??? [41] 90.5% ---------------------------------------------------------------------------- ??? [72] 100.0% [5] 3088(2.2%) 0(0.0%) get-final-combination ...op/src/mainloop.rkt:357:0 infer-splitpoints [11] 100.0% ---------------------------------------------------------------------------- loop [136] 100.0% [6] 1014(0.7%) 0(0.0%) ??? .../herbie/develop/src/core/localize.rkt:43:32 ??? [146] 100.0% ---------------------------------------------------------------------------- loop [149] 100.0% [7] 358(0.3%) 0(0.0%) expand-id-application-form17 (unknown source) syntax-e/no-taint [12] 100.0% ---------------------------------------------------------------------------- loop [149] 100.0% [8] 322(0.2%) 0(0.0%) syntax-e (unknown source) syntax-e/no-taint [12] 100.0% ---------------------------------------------------------------------------- profile-thunk16 [1] 100.0% [9] 141816(100.0%) 0(0.0%) run ...et-7.0/share/pkgs/profile-lib/main.rkt:39:2 ??? [72] 100.0% ---------------------------------------------------------------------------- run-improve40 [2] 100.0% [10] 92488(65.2%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:351:4 run-iter! [13] 100.0% ---------------------------------------------------------------------------- get-final-combination [5] 100.0% [11] 3088(2.2%) 0(0.0%) infer-splitpoints ...lop/src/core/regimes.rkt:34:0 for-loop [14] 100.0% ---------------------------------------------------------------------------- syntax-e [8] 47.4% expand-id-application-form17 [7] 52.6% [12] 680(0.5%) 0(0.0%) syntax-e/no-taint (unknown source) loop [149] 100.0% ---------------------------------------------------------------------------- for-loop [10] 100.0% [13] 92488(65.2%) 0(0.0%) run-iter! ...herbie/develop/src/mainloop.rkt:317:0 simplify! [15] 81.9% finalize-iter! [16] 12.4% localize! [17] 3.9% choose-best-alt! [19] 1.4% gen-rewrites! [20] 0.3% ---------------------------------------------------------------------------- infer-splitpoints [11] 100.0% [14] 3088(2.2%) 0(0.0%) for-loop ...rbie/develop/src/core/regimes.rkt:46:6 option-on-expr [18] 100.0% ---------------------------------------------------------------------------- run-improve40 [2] 0.4% run-iter! [13] 99.6% [15] 76064(53.6%) 0(0.0%) simplify! ...herbie/develop/src/mainloop.rkt:213:0 ??? [21] 100.0% ---------------------------------------------------------------------------- run-iter! [13] 100.0% [16] 11456(8.1%) 0(0.0%) finalize-iter! ...e/develop/src/mainloop.rkt:268:0 ??? [41] 100.0% ---------------------------------------------------------------------------- run-iter! [13] 100.0% [17] 3634(2.6%) 0(0.0%) localize! ...herbie/develop/src/mainloop.rkt:116:0 localize-error [22] 100.0% ---------------------------------------------------------------------------- for-loop [14] 100.0% [18] 3088(2.2%) 0(0.0%) option-on-expr ...velop/src/core/regimes.rkt:150:0 for-loop [23] 50.6% sort-context-on-expr [24] 29.0% err-lsts->split-indices [25] 10.8% for-loop [26] 9.7% ---------------------------------------------------------------------------- run-iter! [13] 100.0% [19] 1336(0.9%) 0(0.0%) choose-best-alt! ...develop/src/mainloop.rkt:107:0 ??? [41] 100.0% ---------------------------------------------------------------------------- run-iter! [13] 100.0% [20] 298(0.2%) 0(0.0%) gen-rewrites! ...ie/develop/src/mainloop.rkt:172:0 for-loop [27] 100.0% ---------------------------------------------------------------------------- simplify! [15] 100.0% [21] 76064(53.6%) 0(0.0%) ??? ...s/herbie/develop/src/core/simplify.rkt:26:0 for-loop [28] 90.7% loop [29] 7.3% one-iter [34] 2.1% ---------------------------------------------------------------------------- localize! [17] 100.0% [22] 3634(2.6%) 0(0.0%) localize-error ...velop/src/core/localize.rkt:52:0 hash-ref! [75] 100.0% ---------------------------------------------------------------------------- option-on-expr [18] 100.0% [23] 1562(1.1%) 0(0.0%) for-loop ...bie/develop/src/core/regimes.rkt:158:4 errors [63] 100.0% ---------------------------------------------------------------------------- option-on-expr [18] 100.0% [24] 896(0.6%) 0(0.0%) sort-context-on-expr ...src/core/regimes.rkt:145:0 ??? [30] 100.0% ---------------------------------------------------------------------------- option-on-expr [18] 100.0% [25] 332(0.2%) 0(0.0%) err-lsts->split-indices .../core/regimes.rkt:281:0 loop [31] 100.0% ---------------------------------------------------------------------------- option-on-expr [18] 100.0% [26] 298(0.2%) 0(0.0%) for-loop ...ie/develop/src/core/regimes.rkt:156:39 posit16< [32] 100.0% ---------------------------------------------------------------------------- gen-rewrites! [20] 100.0% [27] 298(0.2%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:197:4 for-loop [33] 100.0% ---------------------------------------------------------------------------- ??? [21] 100.0% [28] 68954(48.6%) 0(0.0%) for-loop ...bie/develop/src/core/simplify.rkt:33:2 one-iter [34] 100.0% ---------------------------------------------------------------------------- ??? [21] 100.0% [29] 5520(3.9%) 332(0.2%) loop ...herbie/develop/src/core/simplify.rkt:143:2 for-loop [35] 88.5% debug5 [38] 5.5% ---------------------------------------------------------------------------- sort-context-on-expr [24] 100.0% [30] 896(0.6%) 0(0.0%) ??? ...-7.0/collects/racket/private/sort.rkt:369:3 generic-sort/key [36] 100.0% ---------------------------------------------------------------------------- err-lsts->split-indices [25] 100.0% [31] 332(0.2%) 0(0.0%) loop .../herbie/develop/src/core/regimes.rkt:332:4 for-loop [37] 100.0% ---------------------------------------------------------------------------- for-loop [26] 100.0% [32] 298(0.2%) 0(0.0%) posit16< ...pkgs/softposit-rkt/softposit.rkt:224:0 get-ffi-obj* [173] 100.0% ---------------------------------------------------------------------------- for-loop [27] 100.0% [33] 298(0.2%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:198:6 ??? [146] 100.0% ---------------------------------------------------------------------------- ??? [21] 2.3% for-loop [28] 97.7% [34] 70544(49.7%) 0(0.0%) one-iter ...bie/develop/src/core/simplify.rkt:86:0 for-loop [39] 67.8% find-matches [40] 31.7% for-loop [46] 0.4% ---------------------------------------------------------------------------- loop [29] 100.0% [35] 4884(3.4%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:149:4 loop [42] 40.6% for-loop [43] 21.1% expression-cost [50] 13.3% argmin [99] 12.9% for-loop [52] 12.2% ---------------------------------------------------------------------------- ??? [30] 100.0% [36] 896(0.6%) 0(0.0%) generic-sort/key .../racket/private/sort.rkt:156:2 copying-mergesort [44] 66.7% loop [53] 33.3% ---------------------------------------------------------------------------- loop [31] 100.0% [37] 332(0.2%) 0(0.0%) for-loop ...bie/develop/src/core/regimes.rkt:297:4 for-loop [45] 100.0% ---------------------------------------------------------------------------- loop [29] 100.0% [38] 304(0.2%) 0(0.0%) debug5 ...tlies/herbie/develop/src/debug.rkt:102:0 ??? [41] 100.0% ---------------------------------------------------------------------------- one-iter [34] 100.0% [39] 47840(33.7%) 334(0.2%) for-loop ...bie/develop/src/core/simplify.rkt:95:2 loop [47] 98.0% update-leader! [76] 1.3% ---------------------------------------------------------------------------- one-iter [34] 100.0% [40] 22396(15.8%) 0(0.0%) find-matches ...develop/src/core/simplify.rkt:56:0 for-loop [48] 100.0% ---------------------------------------------------------------------------- run-improve40 [2] 1.5% debug5 [38] 1.5% substitute-e [70] 1.6% choose-best-alt! [19] 6.5% variable? [4] 13.7% match-e [61] 20.0% finalize-iter! [16] 55.3% [41] 20702(14.6%) 7608(5.4%) ??? ...contract/private/arrow-val-first.rkt:388:18 for-loop [49] 55.3% ??? [51] 6.5% ---------------------------------------------------------------------------- baseline-error [91] 13.1% for-loop [35] 86.9% [42] 2282(1.6%) 0(0.0%) loop .../racket-7.0/collects/racket/list.rkt:751:4 expression-cost [50] 59.0% for-loop [52] 28.0% ??? [111] 13.1% ---------------------------------------------------------------------------- for-loop [35] 100.0% [43] 1030(0.7%) 704(0.5%) for-loop ...e/develop/src/core/simplify.rkt:153:16 for-loop [54] 31.7% ---------------------------------------------------------------------------- generic-sort/key [36] 100.0% [44] 598(0.4%) 0(0.0%) copying-mergesort ...racket/private/sort.rkt:129:8 loop [53] 100.0% ---------------------------------------------------------------------------- for-loop [37] 100.0% [45] 332(0.2%) 332(0.2%) for-loop ...bie/develop/src/core/regimes.rkt:301:8 ---------------------------------------------------------------------------- one-iter [34] 100.0% [46] 308(0.2%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:111:2 set-precompute! [55] 100.0% ---------------------------------------------------------------------------- for-loop [39] 100.0% [47] 46874(33.1%) 0(0.0%) loop ...herbie/develop/src/core/simplify.rkt:100:4 apply-match [56] 100.0% ---------------------------------------------------------------------------- for-loop [48] 50.0% find-matches [40] 50.0% [48] 22396(15.8%) 1320(0.9%) for-loop ...bie/develop/src/core/simplify.rkt:58:8 for-loop [48] 50.0% match-e [61] 47.1% ---------------------------------------------------------------------------- ??? [41] 100.0% [49] 11456(8.1%) 0(0.0%) for-loop ...ie/develop/src/core/alt-table.rkt:56:2 atab-add-altn [57] 100.0% ---------------------------------------------------------------------------- for-loop [35] 32.5% loop [42] 67.5% [50] 1994(1.4%) 0(0.0%) expression-cost .../develop/src/programs.rkt:204:0 compile [58] 100.0% ---------------------------------------------------------------------------- ??? [41] 100.0% [51] 1336(0.9%) 0(0.0%) ??? .../herbie/develop/src/core/alt-table.rkt:59:0 ??? [146] 100.0% ---------------------------------------------------------------------------- for-loop [35] 48.2% loop [42] 51.8% [52] 1232(0.9%) 0(0.0%) for-loop .../herbie/develop/src/programs.rkt:205:2 table-ref [116] 100.0% ---------------------------------------------------------------------------- generic-sort/key [36] 33.3% copying-mergesort [44] 66.7% [53] 896(0.6%) 0(0.0%) loop ...7.0/collects/racket/private/sort.rkt:96:12 ??? [140] 33.5% ??? [146] 33.3% bf [147] 16.6% ??? [146] 9.8% ??? [148] 2.5% ---------------------------------------------------------------------------- set [133] 100.0% [141] 4430(3.1%) 4430(3.1%) for-loop ...cts/racket/private/set-types.rkt:937:4 ---------------------------------------------------------------------------- custom-set-union [135] 100.0% [142] 1954(1.4%) 1626(1.1%) for-loop ...cts/racket/private/set-types.rkt:174:3 for-loop [150] 16.8% ---------------------------------------------------------------------------- for-loop [143] 4.7% compile-forms31 [120] 23.3% finish-bodys [137] 72.0% [143] 1286(0.9%) 300(0.2%) for-loop (unknown source) finish-bodys [137] 62.7% for-loop [143] 4.7% loop [149] 4.6% dispatch-transformer41 [128] 2.5% expand-identifier [151] 2.2% ---------------------------------------------------------------------------- apply-transformer52 [138] 100.0% [144] 360(0.3%) 360(0.3%) apply-transformer-in-context (unknown source) ---------------------------------------------------------------------------- ??? [140] 100.0% [145] 34930(24.6%) 6230(4.4%) ??? ...t-7.0/collects/racket/private/kw.rkt:592:14 double->posit16 [164] 40.5% create-quire1633 [152] 32.6% big-posit16-add [155] 4.2% big-posit16-mul [156] 3.1% ??? [158] 1.8% ---------------------------------------------------------------------------- for-loop [33] 2.1% loop [53] 2.1% for-loop [124] 2.1% ??? [132] 4.6% loop [136] 4.7% map [121] 6.5% ??? [51] 7.0% ??? [6] 7.1% ??? [72] 8.9% loop [113] 19.7% ??? [140] 35.1% [146] 14220(10.0%) 1366(1.0%) ??? ...tract/private/arrow-higher-order.rkt:357:33 ->flonum [153] 60.2% double->posit16 [164] 11.8% best-alt [157] 7.0% create-quire1633 [152] 4.9% double->quire16 [161] 4.4% location-do [163] 2.1% ---------------------------------------------------------------------------- ??? [140] 100.0% [147] 8430(5.9%) 2472(1.7%) ->bf ...tlies/herbie/develop/src/programs.rkt:62:0 bf [154] 66.6% ??? [158] 4.0% ---------------------------------------------------------------------------- loop [113] 21.7% ??? [140] 78.3% [148] 1644(1.2%) 688(0.5%) ??? ...ects/racket/contract/private/prop.rkt:519:4 ??? [159] 58.2% ---------------------------------------------------------------------------- apply-transformer52 [138] 15.4% for-loop [143] 17.1% syntax-e/no-taint [12] 32.4% loop [103] 35.1% [149] 1048(0.7%) 368(0.3%) loop (unknown source) gf [160] 32.4% expand-id-application-form17 [7] 17.1% syntax-e [8] 15.4% ---------------------------------------------------------------------------- for-loop [142] 100.0% [150] 328(0.2%) 328(0.2%) for-loop ...cts/racket/private/set-types.rkt:178:5 ---------------------------------------------------------------------------- for-loop [143] 100.0% [151] 306(0.2%) 0(0.0%) expand-identifier (unknown source) resolve+shift28 [162] 100.0% ---------------------------------------------------------------------------- ??? [146] 5.7% ??? [145] 94.3% [152] 12076(8.5%) 3836(2.7%) create-quire1633 ...tposit-rkt/softposit.rkt:175:0 double->posit16 [164] 68.2% ---------------------------------------------------------------------------- ??? [146] 100.0% [153] 8900(6.3%) 6668(4.7%) ->flonum ...s/herbie/develop/src/programs.rkt:38:0 ??? [166] 14.1% ??? [170] 3.8% return/no-unsupplied [171] 3.7% ??? [180] 3.5% ---------------------------------------------------------------------------- ->bf [147] 100.0% [154] 5618(4.0%) 448(0.3%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [179] 78.1% ??? [180] 14.0% ---------------------------------------------------------------------------- ??? [145] 100.0% [155] 1454(1.0%) 0(0.0%) big-posit16-add .../src/syntax/softposit.rkt:108:0 ??? [174] 100.0% ---------------------------------------------------------------------------- loop [113] 25.1% ??? [145] 74.9% [156] 1426(1.0%) 0(0.0%) big-posit16-mul .../src/syntax/softposit.rkt:110:0 bf* [165] 100.0% ---------------------------------------------------------------------------- ??? [146] 100.0% [157] 1336(0.9%) 0(0.0%) best-alt .../herbie/develop/src/mainloop.rkt:102:0 argmins [167] 73.2% loop [169] 26.8% ---------------------------------------------------------------------------- ->bf [147] 34.8% ??? [145] 65.2% [158] 976(0.7%) 976(0.7%) ??? ...-7.0/collects/racket/lazy-require.rkt:106:6 ---------------------------------------------------------------------------- ??? [148] 100.0% [159] 956(0.7%) 956(0.7%) ??? ...lects/racket/contract/private/orc.rkt:83:14 ---------------------------------------------------------------------------- loop [149] 100.0% [160] 680(0.5%) 0(0.0%) gf (unknown source) cache-or-reuse-set [168] 100.0% ---------------------------------------------------------------------------- ??? [146] 100.0% [161] 624(0.4%) 300(0.2%) double->quire16 ...ftposit-rkt/softposit.rkt:316:0 get-ffi-obj* [173] 51.9% ---------------------------------------------------------------------------- expand-identifier [151] 100.0% [162] 306(0.2%) 0(0.0%) resolve+shift28 (unknown source) fallback-loop [172] 100.0% ---------------------------------------------------------------------------- location-do [163] 50.0% ??? [146] 50.0% [163] 298(0.2%) 298(0.2%) location-do ...rbie/develop/src/programs.rkt:111:0 location-do [163] 50.0% ---------------------------------------------------------------------------- ??? [146] 7.0% create-quire1633 [152] 34.2% ??? [145] 58.8% [164] 24080(17.0%) 0(0.0%) double->posit16 ...ftposit-rkt/softposit.rkt:248:0 get-ffi-obj* [173] 100.0% ---------------------------------------------------------------------------- big-posit16-mul [156] 100.0% [165] 1426(1.0%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [174] 100.0% ---------------------------------------------------------------------------- ->flonum [153] 100.0% [166] 1258(0.9%) 1258(0.9%) ??? ...s/racket/contract/private/arr-i.rkt:1252:29 ---------------------------------------------------------------------------- best-alt [157] 100.0% [167] 978(0.7%) 0(0.0%) argmins ...lies/herbie/develop/src/common.rkt:93:0 ??? [175] 100.0% ---------------------------------------------------------------------------- gf [160] 100.0% [168] 680(0.5%) 680(0.5%) cache-or-reuse-set (unknown source) ---------------------------------------------------------------------------- best-alt [157] 100.0% [169] 358(0.3%) 0(0.0%) loop ...ghtlies/herbie/develop/src/common.rkt:94:2 ??? [175] 100.0% ---------------------------------------------------------------------------- ->flonum [153] 100.0% [170] 334(0.2%) 334(0.2%) ??? ...ects/racket/contract/private/guts.rkt:746:8 ---------------------------------------------------------------------------- ->flonum [153] 100.0% [171] 330(0.2%) 330(0.2%) return/no-unsupplied ...ct/private/arr-i.rkt:534:0 ---------------------------------------------------------------------------- resolve+shift28 [162] 100.0% [172] 306(0.2%) 306(0.2%) fallback-loop (unknown source) ---------------------------------------------------------------------------- posit16< [32] 1.2% double->quire16 [161] 1.3% double->posit16 [164] 97.5% [173] 24702(17.4%) 5744(4.1%) get-ffi-obj* ...-7.0/collects/ffi/unsafe.rkt:209:2 get-ffi-lib9 [176] 76.7% ---------------------------------------------------------------------------- bf* [165] 49.5% big-posit16-add [155] 50.5% [174] 2880(2.0%) 784(0.6%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [177] 72.8% ---------------------------------------------------------------------------- loop [169] 26.8% argmins [167] 73.2% [175] 1336(0.9%) 0(0.0%) ??? ...lies/herbie/develop/src/mainloop.rkt:104:19 errors [63] 100.0% ---------------------------------------------------------------------------- get-ffi-obj* [173] 100.0% [176] 18958(13.4%) 9662(6.8%) get-ffi-lib9 ...-7.0/collects/ffi/unsafe.rkt:121:0 get-cross-lib-search-dirs [178] 47.4% ---------------------------------------------------------------------------- ??? [174] 100.0% [177] 2096(1.5%) 394(0.3%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [179] 49.4% ??? [180] 31.8% ---------------------------------------------------------------------------- get-ffi-lib9 [176] 100.0% [178] 8990(6.3%) 8006(5.6%) get-cross-lib-search-dirs ...rivate/dirs.rkt:168:7 ??? [181] 10.9% ---------------------------------------------------------------------------- bfmul [177] 19.1% bf [154] 80.9% [179] 5422(3.8%) 5422(3.8%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ---------------------------------------------------------------------------- ->flonum [153] 17.6% bfmul [177] 37.8% bf [154] 44.5% [180] 1760(1.2%) 1760(1.2%) ??? /opt/racket-7.0/collects/ffi/unsafe.rkt:898:4 ---------------------------------------------------------------------------- get-cross-lib-search-dirs [178] 100.0% [181] 984(0.7%) 652(0.5%) ??? ...racket-7.0/collects/racket/promise.rkt:42:2 call-with-exception-handler [182] 33.7% ---------------------------------------------------------------------------- ??? [181] 100.0% [182] 332(0.2%) 0(0.0%) call-with-exception-handler ...re-scheme.rkt:265:2 ??? [183] 100.0% ---------------------------------------------------------------------------- call-with-exception-handler [182] 100.0% [183] 332(0.2%) 0(0.0%) ??? ...acket-7.0/collects/racket/promise.rkt:74:15 find-lib-dir3 [184] 100.0% ---------------------------------------------------------------------------- ??? [183] 100.0% [184] 332(0.2%) 332(0.2%) find-lib-dir3 ...cts/setup/private/dirs.rkt:195:13 ----------------------------------------------------------------------------