Profiling results ----------------- Total cpu time observed: 125400ms (out of 126580ms) Number of samples taken: 367 (once every 342ms) (Hiding functions with self<1.0% and local<2.0%: 3 of 157 hidden) ========================================================================= Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ========================================================================= ??? [50] 100.0% [1] 125400(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [7] 100.0% ------------------------------------------------------------------------- ??? [50] 100.0% [2] 75570(60.3%) 0(0.0%) run-improve40 ...ie/develop/src/mainloop.rkt:336:0 for-loop [8] 98.7% loop [58] 0.9% simplify! [12] 0.5% ------------------------------------------------------------------------- loop [90] 100.0% [3] 5162(4.1%) 0(0.0%) composed ...ollects/racket/private/list.rkt:321:16 ??? [116] 100.0% ------------------------------------------------------------------------- ??? [50] 100.0% [4] 3660(2.9%) 0(0.0%) get-final-combination ...op/src/mainloop.rkt:357:0 infer-splitpoints [9] 100.0% ------------------------------------------------------------------------- substitute-e [71] 23.8% match-e [55] 76.2% [5] 1286(1.0%) 0(0.0%) variable? .../develop/src/syntax/syntax.rkt:1159:0 ??? [18] 100.0% ------------------------------------------------------------------------- copying-mergesort [40] 100.0% [6] 324(0.3%) 0(0.0%) jloop ...0/collects/racket/private/sort.rkt:120:23 ??? [116] 100.0% ------------------------------------------------------------------------- profile-thunk16 [1] 100.0% [7] 125400(100.0%) 0(0.0%) run ...et-7.0/share/pkgs/profile-lib/main.rkt:39:2 ??? [50] 100.0% ------------------------------------------------------------------------- run-improve40 [2] 100.0% [8] 74562(59.5%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:351:4 run-iter! [10] 100.0% ------------------------------------------------------------------------- get-final-combination [4] 100.0% [9] 3660(2.9%) 0(0.0%) infer-splitpoints ...lop/src/core/regimes.rkt:34:0 for-loop [11] 100.0% ------------------------------------------------------------------------- for-loop [8] 100.0% [10] 74562(59.5%) 0(0.0%) run-iter! ...herbie/develop/src/mainloop.rkt:317:0 simplify! [12] 71.8% finalize-iter! [13] 21.2% localize! [14] 5.6% choose-best-alt! [16] 1.4% ------------------------------------------------------------------------- infer-splitpoints [9] 100.0% [11] 3660(2.9%) 0(0.0%) for-loop ...rbie/develop/src/core/regimes.rkt:46:6 option-on-expr [15] 100.0% ------------------------------------------------------------------------- run-improve40 [2] 0.7% run-iter! [10] 99.3% [12] 53898(43.0%) 0(0.0%) simplify! ...herbie/develop/src/mainloop.rkt:213:0 ??? [17] 100.0% ------------------------------------------------------------------------- run-iter! [10] 100.0% [13] 15790(12.6%) 0(0.0%) finalize-iter! ...e/develop/src/mainloop.rkt:268:0 ??? [18] 100.0% ------------------------------------------------------------------------- run-iter! [10] 100.0% [14] 4200(3.3%) 0(0.0%) localize! ...herbie/develop/src/mainloop.rkt:116:0 localize-error [19] 100.0% ------------------------------------------------------------------------- for-loop [11] 100.0% [15] 3660(2.9%) 0(0.0%) option-on-expr ...velop/src/core/regimes.rkt:150:0 for-loop [20] 45.2% sort-context-on-expr [21] 26.6% loop [90] 19.2% err-lsts->split-indices [22] 9.0% ------------------------------------------------------------------------- run-iter! [10] 100.0% [16] 1030(0.8%) 0(0.0%) choose-best-alt! ...develop/src/mainloop.rkt:107:0 ??? [18] 100.0% ------------------------------------------------------------------------- simplify! [12] 100.0% [17] 53898(43.0%) 0(0.0%) ??? ...s/herbie/develop/src/core/simplify.rkt:26:0 for-loop [23] 88.6% loop [25] 9.1% one-iter [29] 2.3% ------------------------------------------------------------------------- inductor [53] 0.9% choose-best-alt! [16] 5.3% variable? [5] 6.6% match-e [55] 6.9% finalize-iter! [13] 80.3% [18] 19448(15.5%) 2982(2.4%) ??? ...contract/private/arrow-val-first.rkt:388:18 for-loop [24] 80.3% ??? [26] 5.3% ------------------------------------------------------------------------- localize! [14] 100.0% [19] 4200(3.3%) 0(0.0%) localize-error ...velop/src/core/localize.rkt:52:0 hash-ref! [59] 100.0% ------------------------------------------------------------------------- option-on-expr [15] 100.0% [20] 1656(1.3%) 0(0.0%) for-loop ...bie/develop/src/core/regimes.rkt:158:4 errors [37] 100.0% ------------------------------------------------------------------------- option-on-expr [15] 100.0% [21] 974(0.8%) 0(0.0%) sort-context-on-expr ...src/core/regimes.rkt:145:0 ??? [27] 100.0% ------------------------------------------------------------------------- option-on-expr [15] 100.0% [22] 328(0.3%) 0(0.0%) err-lsts->split-indices .../core/regimes.rkt:281:0 loop [28] 100.0% ------------------------------------------------------------------------- ??? [17] 100.0% [23] 47736(38.1%) 0(0.0%) for-loop ...bie/develop/src/core/simplify.rkt:33:2 one-iter [29] 100.0% ------------------------------------------------------------------------- ??? [18] 100.0% [24] 15790(12.6%) 0(0.0%) for-loop ...ie/develop/src/core/alt-table.rkt:56:2 atab-add-altn [30] 88.5% loop [32] 11.5% ------------------------------------------------------------------------- ??? [17] 100.0% [25] 4916(3.9%) 328(0.3%) loop ...herbie/develop/src/core/simplify.rkt:143:2 for-loop [31] 87.2% debug5 [35] 6.1% ------------------------------------------------------------------------- ??? [18] 100.0% [26] 1030(0.8%) 0(0.0%) ??? .../herbie/develop/src/core/alt-table.rkt:59:0 ??? [123] 100.0% ------------------------------------------------------------------------- sort-context-on-expr [21] 100.0% [27] 974(0.8%) 0(0.0%) ??? ...-7.0/collects/racket/private/sort.rkt:369:3 generic-sort/key [33] 100.0% ------------------------------------------------------------------------- err-lsts->split-indices [22] 100.0% [28] 328(0.3%) 0(0.0%) loop .../herbie/develop/src/core/regimes.rkt:332:4 for-loop [34] 100.0% ------------------------------------------------------------------------- ??? [17] 2.5% for-loop [23] 97.5% [29] 48982(39.1%) 0(0.0%) one-iter ...bie/develop/src/core/simplify.rkt:86:0 for-loop [36] 67.7% find-matches [38] 32.3% ------------------------------------------------------------------------- for-loop [24] 100.0% [30] 13982(11.1%) 0(0.0%) atab-add-altn ...elop/src/core/alt-table.rkt:202:0 errors [37] 95.8% override-at-pnts [41] 4.2% ------------------------------------------------------------------------- loop [25] 100.0% [31] 4288(3.4%) 0(0.0%) for-loop ...ie/develop/src/core/simplify.rkt:149:4 loop [39] 53.5% argmin [68] 22.7% expression-cost [46] 15.5% make-sequence [70] 8.3% ------------------------------------------------------------------------- for-loop [24] 100.0% [32] 1808(1.4%) 1808(1.4%) loop ...erbie/develop/src/core/alt-table.rkt:176:2 ------------------------------------------------------------------------- ??? [27] 100.0% [33] 974(0.8%) 0(0.0%) generic-sort/key .../racket/private/sort.rkt:156:2 copying-mergesort [40] 100.0% ------------------------------------------------------------------------- loop [28] 100.0% [34] 328(0.3%) 0(0.0%) for-loop ...bie/develop/src/core/regimes.rkt:297:4 for-loop [42] 100.0% ------------------------------------------------------------------------- loop [25] 100.0% [35] 300(0.2%) 300(0.2%) debug5 ...tlies/herbie/develop/src/debug.rkt:102:0 ------------------------------------------------------------------------- one-iter [29] 100.0% [36] 33160(26.4%) 0(0.0%) for-loop ...bie/develop/src/core/simplify.rkt:95:2 loop [43] 99.0% update-leader! [80] 1.0% ------------------------------------------------------------------------- ??? [147] 4.4% for-loop [20] 7.0% ??? [50] 31.9% atab-add-altn [30] 56.7% [37] 23626(18.8%) 0(0.0%) errors ...lies/herbie/develop/src/points.rkt:228:0 for-loop [44] 98.5% eval-prog [48] 1.5% ------------------------------------------------------------------------- one-iter [29] 100.0% [38] 15822(12.6%) 0(0.0%) find-matches ...develop/src/core/simplify.rkt:56:0 for-loop [45] 100.0% ------------------------------------------------------------------------- for-loop [31] 100.0% [39] 2294(1.8%) 0(0.0%) loop .../racket-7.0/collects/racket/list.rkt:751:4 for-loop [81] 55.8% expression-cost [46] 44.2% ------------------------------------------------------------------------- generic-sort/key [33] 41.7% copying-mergesort [40] 58.3% [40] 974(0.8%) 326(0.3%) copying-mergesort ...racket/private/sort.rkt:129:8 copying-mergesort [40] 58.3% loop [49] 16.6% jloop [6] 8.3% ------------------------------------------------------------------------- atab-add-altn [30] 100.0% [41] 584(0.5%) 0(0.0%) override-at-pnts ...p/src/core/alt-table.rkt:138:0 for-loop [47] 100.0% ------------------------------------------------------------------------- for-loop [34] 100.0% [42] 328(0.3%) 0(0.0%) for-loop ...bie/develop/src/core/regimes.rkt:301:8 curried [112] 100.0% ------------------------------------------------------------------------- for-loop [36] 100.0% [43] 32812(26.2%) 0(0.0%) loop ...herbie/develop/src/core/simplify.rkt:100:4 apply-match [51] 100.0% ------------------------------------------------------------------------- errors [37] 100.0% [44] 23272(18.6%) 918(0.7%) for-loop ...es/herbie/develop/src/points.rkt:230:2 ??? [50] 96.1% ------------------------------------------------------------------------- for-loop [45] 50.0% find-matches [38] 50.0% [45] 15822(12.6%) 1598(1.3%) for-loop ...bie/develop/src/core/simplify.rkt:58:8 for-loop [45] 50.0% match-e [55] 45.0% ------------------------------------------------------------------------- argmin [68] 27.9% for-loop [31] 28.5% loop [39] 43.6% [46] 2328(1.9%) 352(0.3%) expression-cost .../develop/src/programs.rkt:204:0 compile [52] 84.9% ------------------------------------------------------------------------- override-at-pnts [41] 100.0% [47] 584(0.5%) 584(0.5%) for-loop .../develop/src/core/alt-table.rkt:139:22 ------------------------------------------------------------------------- errors [37] 100.0% [48] 354(0.3%) 0(0.0%) eval-prog ...herbie/develop/src/programs.rkt:130:0 inductor [53] 100.0% ------------------------------------------------------------------------- copying-mergesort [40] 100.0% [49] 324(0.3%) 0(0.0%) loop ...7.0/collects/racket/private/sort.rkt:96:12 bf [124] 12.2% ??? [125] 9.1% ------------------------------------------------------------------------- for-loop [94] 43.0% for-loop [111] 57.0% [117] 2396(1.9%) 0(0.0%) hash-update! ...cket/private/more-scheme.rkt:365:4 ??? [126] 100.0% ------------------------------------------------------------------------- foldl [108] 100.0% [118] 2022(1.6%) 2022(1.6%) merge2 .../herbie/develop/src/core/ematch.rkt:24:0 ------------------------------------------------------------------------- custom-set-union [109] 100.0% [119] 1584(1.3%) 1260(1.0%) for-loop ...cts/racket/private/set-types.rkt:174:3 for-loop [127] 20.5% ------------------------------------------------------------------------- for-loop [105] 34.3% point-error [114] 65.7% [120] 944(0.8%) 648(0.5%) ulp-difference ...erbie/develop/src/float.rkt:14:0 f9 [67] 31.4% ------------------------------------------------------------------------- new-enode [115] 100.0% [121] 306(0.2%) 306(0.2%) type-of-enode-expr ...elop/src/core/enode.rkt:69:0 ------------------------------------------------------------------------- ??? [116] 100.0% [122] 31772(25.3%) 2518(2.0%) ??? ...t-7.0/collects/racket/private/kw.rkt:592:14 double->posit16 [136] 55.4% create-quire1633 [129] 30.3% big-posit16-add [134] 2.2% big-posit16-mul [135] 2.2% ??? [137] 2.0% ------------------------------------------------------------------------- for-loop [105] 1.4% ??? [104] 1.4% ??? [26] 4.0% ??? [50] 4.6% loop [95] 7.6% map [76] 10.4% loop [90] 29.3% ??? [116] 39.7% [123] 21462(17.1%) 994(0.8%) ??? ...tract/private/arrow-higher-order.rkt:357:33 ->flonum [128] 58.1% double->posit16 [136] 28.6% best-alt [133] 4.0% create-quire1633 [129] 3.1% ------------------------------------------------------------------------- ??? [116] 100.0% [124] 6354(5.1%) 1862(1.5%) ->bf ...tlies/herbie/develop/src/programs.rkt:62:0 bf [131] 65.4% ??? [137] 5.3% ------------------------------------------------------------------------- ??? [104] 5.6% ??? [50] 6.1% ??? [116] 88.3% [125] 5344(4.3%) 712(0.6%) ??? ...ects/racket/contract/private/prop.rkt:519:4 ??? [130] 86.7% ------------------------------------------------------------------------- hash-update! [117] 100.0% [126] 2396(1.9%) 336(0.3%) ??? ...s/herbie/develop/src/core/egraph.rkt:221:24 for-loop [132] 86.0% ------------------------------------------------------------------------- for-loop [119] 100.0% [127] 324(0.3%) 324(0.3%) for-loop ...cts/racket/private/set-types.rkt:178:5 ------------------------------------------------------------------------- ??? [123] 100.0% [128] 12630(10.1%) 8480(6.8%) ->flonum ...s/herbie/develop/src/programs.rkt:38:0 ??? [138] 9.8% ??? [137] 7.8% return/no-unsupplied [139] 7.5% keyword-apply [142] 5.4% list-member? [144] 2.4% ------------------------------------------------------------------------- ??? [123] 6.4% ??? [122] 93.6% [129] 10296(8.2%) 1278(1.0%) create-quire1633 ...tposit-rkt/softposit.rkt:175:0 double->posit16 [136] 87.6% ------------------------------------------------------------------------- ??? [125] 100.0% [130] 4632(3.7%) 1600(1.3%) ??? ...lects/racket/contract/private/orc.rkt:83:14 ??? [137] 65.5% ------------------------------------------------------------------------- ->bf [124] 100.0% [131] 4154(3.3%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [152] 84.8% ??? [153] 15.2% ------------------------------------------------------------------------- ??? [126] 100.0% [132] 2060(1.6%) 2060(1.6%) for-loop ...bie/develop/src/core/egraph.rkt:222:26 ------------------------------------------------------------------------- ??? [123] 100.0% [133] 1030(0.8%) 0(0.0%) best-alt .../herbie/develop/src/mainloop.rkt:102:0 argmins [140] 68.5% loop [143] 31.5% ------------------------------------------------------------------------- ??? [122] 100.0% [134] 712(0.6%) 0(0.0%) big-posit16-add .../src/syntax/softposit.rkt:108:0 ??? [146] 100.0% ------------------------------------------------------------------------- ??? [122] 100.0% [135] 698(0.6%) 0(0.0%) big-posit16-mul .../src/syntax/softposit.rkt:110:0 bf* [141] 100.0% ------------------------------------------------------------------------- ??? [123] 18.7% create-quire1633 [129] 27.5% ??? [122] 53.7% [136] 32742(26.1%) 296(0.2%) double->posit16 ...ftposit-rkt/softposit.rkt:248:0 get-ffi-obj* [145] 99.1% ------------------------------------------------------------------------- =-or-nan? [113] 5.9% ->bf [124] 6.4% ??? [122] 11.8% ->flonum [128] 18.6% ??? [130] 57.3% [137] 5292(4.2%) 5292(4.2%) ??? ...-7.0/collects/racket/lazy-require.rkt:106:6 ------------------------------------------------------------------------- ->flonum [128] 100.0% [138] 1242(1.0%) 1242(1.0%) ??? ...s/racket/contract/private/arr-i.rkt:1252:29 ------------------------------------------------------------------------- ->flonum [128] 100.0% [139] 944(0.8%) 298(0.2%) return/no-unsupplied ...ct/private/arr-i.rkt:534:0 filter [148] 68.4% ------------------------------------------------------------------------- best-alt [133] 100.0% [140] 706(0.6%) 0(0.0%) argmins ...lies/herbie/develop/src/common.rkt:93:0 ??? [147] 100.0% ------------------------------------------------------------------------- big-posit16-mul [135] 100.0% [141] 698(0.6%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [146] 100.0% ------------------------------------------------------------------------- ->flonum [128] 100.0% [142] 682(0.5%) 682(0.5%) keyword-apply ...lects/racket/private/kw.rkt:421:2 ------------------------------------------------------------------------- best-alt [133] 100.0% [143] 324(0.3%) 0(0.0%) loop ...ghtlies/herbie/develop/src/common.rkt:94:2 ??? [147] 100.0% ------------------------------------------------------------------------- ->flonum [128] 100.0% [144] 298(0.2%) 298(0.2%) list-member? ...llects/racket/private/set.rkt:24:0 ------------------------------------------------------------------------- double->posit16 [136] 99.0% [145] 32778(26.1%) 7176(5.7%) get-ffi-obj* ...-7.0/collects/ffi/unsafe.rkt:209:2 get-ffi-lib9 [149] 78.1% ------------------------------------------------------------------------- bf* [141] 49.5% big-posit16-add [134] 50.5% [146] 1410(1.1%) 698(0.6%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfadd [150] 50.5% ------------------------------------------------------------------------- loop [143] 31.5% argmins [140] 68.5% [147] 1030(0.8%) 0(0.0%) ??? ...lies/herbie/develop/src/mainloop.rkt:104:19 errors [37] 100.0% ------------------------------------------------------------------------- return/no-unsupplied [139] 100.0% [148] 646(0.5%) 646(0.5%) filter ...0/collects/racket/private/list.rkt:256:2 ------------------------------------------------------------------------- get-ffi-obj* [145] 100.0% [149] 25602(20.4%) 13560(10.8%) get-ffi-lib9 ...-7.0/collects/ffi/unsafe.rkt:121:0 get-cross-lib-search-dirs [151] 47.0% ------------------------------------------------------------------------- ??? [146] 100.0% [150] 712(0.6%) 0(0.0%) bfadd ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [152] 52.2% ??? [153] 47.8% ------------------------------------------------------------------------- get-ffi-lib9 [149] 100.0% [151] 12042(9.6%) 10424(8.3%) get-cross-lib-search-dirs ...rivate/dirs.rkt:168:7 ??? [154] 13.4% ------------------------------------------------------------------------- bfadd [150] 9.6% bf [131] 90.4% [152] 3894(3.1%) 3894(3.1%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ------------------------------------------------------------------------- bfadd [150] 35.0% bf [131] 65.0% [153] 972(0.8%) 972(0.8%) ??? /opt/racket-7.0/collects/ffi/unsafe.rkt:898:4 ------------------------------------------------------------------------- get-cross-lib-search-dirs [151]100.0% [154] 1618(1.3%) 1618(1.3%) ??? ...racket-7.0/collects/racket/promise.rkt:42:2 -------------------------------------------------------------------------