Profiling results ----------------- Total cpu time observed: 26222ms (out of 26868ms) Number of samples taken: 78 (once every 336ms) ====================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ====================================================================== ??? [7] 100.0% [1] 26222(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [4] 100.0% ---------------------------------------------------------------------- ??? [7] 100.0% [2] 6320(24.1%) 0(0.0%) run-improve40 ...ie/develop/src/mainloop.rkt:336:0 for-loop [5] 95.2% loop [9] 4.8% ---------------------------------------------------------------------- ??? [7] 100.0% [3] 2160(8.2%) 0(0.0%) oracle-error ...erbie/develop/src/points.rkt:212:0 for-loop [6] 100.0% ---------------------------------------------------------------------- profile-thunk16 [1] 100.0% [4] 26222(100.0%) 0(0.0%) run ...et-7.0/share/pkgs/profile-lib/main.rkt:39:2 ??? [7] 100.0% ---------------------------------------------------------------------- run-improve40 [2] 100.0% [5] 6016(22.9%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:351:4 run-iter! [8] 100.0% ---------------------------------------------------------------------- oracle-error [3] 100.0% [6] 2160(8.2%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:213:2 loop [37] 57.3% map [67] 42.7% ---------------------------------------------------------------------- run [4] 50.0% [7] 26222(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 50.0% loop [9] 24.4% run-improve40 [2] 12.1% errors [38] 4.7% oracle-error [3] 4.1% eval-errors [12] 3.4% get-final-combination [14] 1.3% ---------------------------------------------------------------------- for-loop [5] 100.0% [8] 6016(22.9%) 0(0.0%) run-iter! ...herbie/develop/src/mainloop.rkt:317:0 finalize-iter! [10] 42.1% simplify! [11] 35.9% localize! [13] 22.0% ---------------------------------------------------------------------- run-improve40 [2] 2.3% ??? [7] 97.7% [9] 13080(49.9%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:303:2 loop [23] 65.1% loop [15] 29.7% for-loop [21] 2.7% filter-p&e [22] 2.5% ---------------------------------------------------------------------- run-iter! [8] 100.0% [10] 2532(9.7%) 0(0.0%) finalize-iter! ...e/develop/src/mainloop.rkt:268:0 ??? [16] 100.0% ---------------------------------------------------------------------- run-iter! [8] 100.0% [11] 2160(8.2%) 0(0.0%) simplify! ...herbie/develop/src/mainloop.rkt:213:0 ??? [17] 100.0% ---------------------------------------------------------------------- ??? [7] 100.0% [12] 1790(6.8%) 0(0.0%) eval-errors ...herbie/develop/src/points.rkt:203:0 for-loop [18] 100.0% ---------------------------------------------------------------------- run-iter! [8] 100.0% [13] 1324(5.0%) 0(0.0%) localize! ...herbie/develop/src/mainloop.rkt:116:0 localize-error [19] 100.0% ---------------------------------------------------------------------- ??? [7] 100.0% [14] 696(2.7%) 0(0.0%) get-final-combination ...op/src/mainloop.rkt:357:0 infer-splitpoints [20] 100.0% ---------------------------------------------------------------------- loop [9] 100.0% [15] 3888(14.8%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:264:2 loop [23] 70.4% make-exacts-walkup [27] 29.6% ---------------------------------------------------------------------- finalize-iter! [10] 100.0% [16] 2532(9.7%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:388:18 for-loop [24] 100.0% ---------------------------------------------------------------------- simplify! [11] 100.0% [17] 2160(8.2%) 0(0.0%) ??? ...s/herbie/develop/src/core/simplify.rkt:26:0 for-loop [25] 100.0% ---------------------------------------------------------------------- eval-errors [12] 100.0% [18] 1790(6.8%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:205:2 ??? [75] 100.0% ---------------------------------------------------------------------- localize! [13] 100.0% [19] 1324(5.0%) 0(0.0%) localize-error ...velop/src/core/localize.rkt:52:0 hash-ref! [26] 100.0% ---------------------------------------------------------------------- get-final-combination [14] 100.0% [20] 696(2.7%) 0(0.0%) infer-splitpoints ...lop/src/core/regimes.rkt:34:0 for-loop [28] 100.0% ---------------------------------------------------------------------- loop [9] 100.0% [21] 358(1.4%) 358(1.4%) for-loop ...s/herbie/develop/src/points.rkt:322:31 ---------------------------------------------------------------------- loop [9] 100.0% [22] 324(1.2%) 0(0.0%) filter-p&e .../herbie/develop/src/points.rkt:277:0 for-loop [29] 100.0% ---------------------------------------------------------------------- loop [15] 24.3% loop [9] 75.7% [23] 11248(42.9%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:247:4 loop [37] 96.7% loop [34] 3.3% ---------------------------------------------------------------------- ??? [16] 100.0% [24] 2532(9.7%) 0(0.0%) for-loop ...ie/develop/src/core/alt-table.rkt:56:2 atab-add-altn [30] 88.2% loop [36] 11.8% ---------------------------------------------------------------------- ??? [17] 100.0% [25] 2160(8.2%) 0(0.0%) for-loop ...bie/develop/src/core/simplify.rkt:33:2 one-iter [31] 100.0% ---------------------------------------------------------------------- loop [37] 8.7% localize-error [19] 34.7% map [67] 56.6% [26] 1324(5.0%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 f9 [61] 52.1% parse-loop15 [32] 47.9% ---------------------------------------------------------------------- loop [15] 100.0% [27] 1150(4.4%) 0(0.0%) make-exacts-walkup ...develop/src/points.rkt:244:0 loop [37] 100.0% ---------------------------------------------------------------------- infer-splitpoints [20] 100.0% [28] 696(2.7%) 0(0.0%) for-loop ...rbie/develop/src/core/regimes.rkt:46:6 option-on-expr [33] 100.0% ---------------------------------------------------------------------- filter-p&e [22] 100.0% [29] 324(1.2%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:279:2 ordinary-value? [35] 100.0% ---------------------------------------------------------------------- for-loop [24] 100.0% [30] 2234(8.5%) 0(0.0%) atab-add-altn ...elop/src/core/alt-table.rkt:202:0 errors [38] 100.0% ---------------------------------------------------------------------- for-loop [25] 100.0% [31] 2160(8.2%) 0(0.0%) one-iter ...bie/develop/src/core/simplify.rkt:86:0 for-loop [39] 69.6% find-matches [41] 30.4% ---------------------------------------------------------------------- hash-ref! [26] 100.0% [32] 1324(5.0%) 0(0.0%) parse-loop15 ...s/racket/match/compiler.rkt:418:15 loop [37] 51.4% loop [42] 37.4% map [67] 11.3% ---------------------------------------------------------------------- for-loop [28] 100.0% [33] 696(2.7%) 0(0.0%) option-on-expr ...velop/src/core/regimes.rkt:150:0 sort-context-on-expr [40] 100.0% ---------------------------------------------------------------------- loop [23] 100.0% [34] 370(1.4%) 0(0.0%) loop ...-7.0/collects/racket/private/map.rkt:277:4 =-or-nan? [43] 100.0% ---------------------------------------------------------------------- for-loop [29] 100.0% [35] 324(1.2%) 324(1.2%) ordinary-value? ...rbie/develop/src/float.rkt:54:0 ---------------------------------------------------------------------- for-loop [24] 100.0% [36] 298(1.1%) 0(0.0%) loop ...erbie/develop/src/core/alt-table.rkt:176:2 loop [44] 100.0% ---------------------------------------------------------------------- make-exacts-walkup [27] 0.0% parse-loop15 [32] 0.2% loop [23] 0.4% loop! [70] 0.5% for-loop [6] 8.7% loop [37] 90.1% [37] 14246(54.3%) 304(1.2%) loop ...-7.0/collects/racket/private/map.rkt:40:19 loop [37] 90.1% ??? [75] 6.6% ??? [79] 2.1% loop! [70] 0.5% ??? [77] 0.3% map [67] 0.1% hash-ref! [26] 0.1% composed [45] 0.1% ---------------------------------------------------------------------- atab-add-altn [30] 47.4% ??? [7] 52.6% [38] 4714(18.0%) 0(0.0%) errors ...lies/herbie/develop/src/points.rkt:228:0 for-loop [46] 100.0% ---------------------------------------------------------------------- one-iter [31] 100.0% [39] 1504(5.7%) 0(0.0%) for-loop ...bie/develop/src/core/simplify.rkt:95:2 loop [47] 100.0% ---------------------------------------------------------------------- option-on-expr [33] 100.0% [40] 696(2.7%) 0(0.0%) sort-context-on-expr ...src/core/regimes.rkt:145:0 ??? [48] 100.0% ---------------------------------------------------------------------- one-iter [31] 100.0% [41] 656(2.5%) 0(0.0%) find-matches ...develop/src/core/simplify.rkt:56:0 for-loop [49] 100.0% ---------------------------------------------------------------------- parse-loop15 [32] 2.8% loop [42] 97.2% [42] 644(2.5%) 0(0.0%) loop ...-7.0/collects/racket/private/map.rkt:54:19 loop [42] 97.2% ulp-difference [55] 1.6% ??? [77] 1.2% ---------------------------------------------------------------------- loop [34] 100.0% [43] 370(1.4%) 370(1.4%) =-or-nan? ...ies/herbie/develop/src/float.rkt:76:0 ---------------------------------------------------------------------- loop [36] 100.0% [44] 298(1.1%) 298(1.1%) loop ...erbie/develop/src/core/alt-table.rkt:101:2 ---------------------------------------------------------------------- loop [37] 100.0% [45] 4876(18.6%) 0(0.0%) composed ...ollects/racket/private/list.rkt:321:16 ??? [75] 100.0% ---------------------------------------------------------------------- errors [38] 100.0% [46] 4714(18.0%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:230:2 ??? [75] 80.0% point-error [52] 6.8% ??? [77] 6.6% ulp-difference [55] 6.6% ---------------------------------------------------------------------- for-loop [39] 100.0% [47] 1504(5.7%) 0(0.0%) loop ...herbie/develop/src/core/simplify.rkt:100:4 apply-match [50] 100.0% ---------------------------------------------------------------------- sort-context-on-expr [40] 100.0% [48] 696(2.7%) 0(0.0%) ??? ...-7.0/collects/racket/private/sort.rkt:369:3 loop [53] 51.7% generic-sort/key [54] 48.3% ---------------------------------------------------------------------- for-loop [49] 50.0% find-matches [41] 50.0% [49] 656(2.5%) 0(0.0%) for-loop ...bie/develop/src/core/simplify.rkt:58:8 for-loop [49] 50.0% match-e [51] 50.0% ---------------------------------------------------------------------- loop [47] 100.0% [50] 1504(5.7%) 0(0.0%) apply-match .../develop/src/core/simplify.rkt:65:0 for-loop [56] 60.1% for-loop [59] 20.2% set [60] 19.7% ---------------------------------------------------------------------- for-loop [62] 50.0% for-loop [49] 50.0% [51] 656(2.5%) 0(0.0%) match-e ...herbie/develop/src/core/ematch.rkt:39:0 for-loop [57] 100.0% ---------------------------------------------------------------------- map [67] 48.1% for-loop [46] 51.9% [52] 620(2.4%) 0(0.0%) point-error ...herbie/develop/src/points.rkt:195:0 ulp-difference [55] 100.0% ---------------------------------------------------------------------- ??? [48] 100.0% [53] 360(1.4%) 0(0.0%) loop ...7.0/collects/racket/private/sort.rkt:96:12 ??? [75] 100.0% ---------------------------------------------------------------------- ??? [48] 100.0% [54] 336(1.3%) 0(0.0%) generic-sort/key .../racket/private/sort.rkt:156:2 copying-mergesort [58] 100.0% ---------------------------------------------------------------------- for-loop [46] 24.3% loop [42] 27.1% point-error [52] 48.6% [55] 1276(4.9%) 0(0.0%) ulp-difference ...erbie/develop/src/float.rkt:14:0 f9 [61] 100.0% ---------------------------------------------------------------------- apply-match [50] 100.0% [56] 904(3.4%) 0(0.0%) for-loop ...bie/develop/src/core/simplify.rkt:78:2 substitute-e [64] 33.6% dedup-children! [65] 33.2% merge-egraph-nodes! [66] 33.2% ---------------------------------------------------------------------- match-e [51] 100.0% [57] 656(2.5%) 656(2.5%) for-loop ...rbie/develop/src/core/ematch.rkt:52:11 for-loop [62] 50.0% ---------------------------------------------------------------------- generic-sort/key [54] 25.0% copying-mergesort [58] 75.0% [58] 336(1.3%) 0(0.0%) copying-mergesort ...racket/private/sort.rkt:129:8 copying-mergesort [58] 75.0% jloop [63] 25.0% ---------------------------------------------------------------------- apply-match [50] 100.0% [59] 304(1.2%) 304(1.2%) for-loop ...ects/racket/private/set-types.rkt:75:2 ---------------------------------------------------------------------- apply-match [50] 100.0% [60] 296(1.1%) 296(1.1%) set ...collects/racket/private/set-types.rkt:981:0 ---------------------------------------------------------------------- ulp-difference [55] 48.9% hash-ref! [26] 51.1% [61] 2254(8.6%) 1276(4.9%) f9 ....0/collects/racket/match/compiler.rkt:507:40 map [67] 51.1% ---------------------------------------------------------------------- for-loop [57] 100.0% [62] 656(2.5%) 0(0.0%) for-loop ...rbie/develop/src/core/ematch.rkt:58:37 match-e [51] 100.0% ---------------------------------------------------------------------- copying-mergesort [58] 100.0% [63] 336(1.3%) 0(0.0%) jloop ...0/collects/racket/private/sort.rkt:120:23 ??? [75] 100.0% ---------------------------------------------------------------------- for-loop [56] 100.0% [64] 304(1.2%) 0(0.0%) substitute-e ...e/develop/src/core/ematch.rkt:64:0 for-loop [68] 100.0% ---------------------------------------------------------------------- for-loop [56] 100.0% [65] 300(1.1%) 0(0.0%) dedup-children! ...evelop/src/core/enode.rkt:199:0 loop [69] 100.0% ---------------------------------------------------------------------- for-loop [56] 100.0% [66] 300(1.1%) 0(0.0%) merge-egraph-nodes! ...p/src/core/egraph.rkt:145:0 loop! [70] 100.0% ---------------------------------------------------------------------- parse-loop15 [32] 3.3% loop [37] 12.7% for-loop [6] 41.1% f9 [61] 43.0% [67] 2246(8.6%) 0(0.0%) map ...t-7.0/collects/racket/private/map.rkt:35:13 hash-ref! [26] 46.3% ??? [71] 27.8% point-error [52] 13.3% ??? [77] 12.7% ---------------------------------------------------------------------- substitute-e [64] 100.0% [68] 304(1.2%) 0(0.0%) for-loop ...rbie/develop/src/core/ematch.rkt:73:24 mk-enode! [72] 100.0% ---------------------------------------------------------------------- dedup-children! [65] 20.0% loop [69] 80.0% [69] 300(1.1%) 0(0.0%) loop ...racket-7.0/collects/racket/list.rkt:417:34 loop [69] 80.0% member [74] 20.0% ---------------------------------------------------------------------- loop [37] 50.0% merge-egraph-nodes! [66] 50.0% [70] 300(1.1%) 0(0.0%) loop! ...s/herbie/develop/src/core/enode.rkt:191:2 loop [37] 50.0% custom-set-union [73] 50.0% ---------------------------------------------------------------------- map [67] 100.0% [71] 624(2.4%) 0(0.0%) ??? ...htlies/herbie/develop/src/points.rkt:214:26 ??? [75] 100.0% ---------------------------------------------------------------------- for-loop [68] 100.0% [72] 304(1.2%) 304(1.2%) mk-enode! ...rbie/develop/src/core/egraph.rkt:99:0 ---------------------------------------------------------------------- loop! [70] 100.0% [73] 300(1.1%) 300(1.1%) custom-set-union ...et/private/set-types.rkt:168:0 ---------------------------------------------------------------------- loop [69] 100.0% [74] 300(1.1%) 300(1.1%) member (unknown source) ---------------------------------------------------------------------- jloop [63] 2.4% loop [53] 2.5% ??? [71] 4.4% for-loop [18] 12.6% loop [37] 17.5% for-loop [46] 26.5% composed [45] 34.2% [75] 14248(54.3%) 0(0.0%) ??? ...tlies/herbie/develop/src/programs.rkt:146:2 ??? [76] 60.7% ->bf [78] 27.8% ??? [77] 9.1% ??? [79] 2.4% ---------------------------------------------------------------------- ??? [75] 100.0% [76] 8652(33.0%) 934(3.6%) ??? ...t-7.0/collects/racket/private/kw.rkt:592:14 create-quire1633 [80] 49.8% big-posit16-mul [83] 15.7% double->posit16 [89] 11.0% big-posit16-add [84] 8.7% big-posit16-sqrt [87] 4.1% ---------------------------------------------------------------------- loop [42] 3.8% for-loop [46] 4.0% map [67] 8.6% ??? [75] 16.4% loop [37] 67.2% [77] 7876(30.0%) 1334(5.1%) ??? ...tract/private/arrow-higher-order.rkt:357:33 double->posit16 [89] 57.8% ->flonum [82] 20.8% ??? [88] 4.5% ---------------------------------------------------------------------- ??? [75] 100.0% [78] 3968(15.1%) 0(0.0%) ->bf ...tlies/herbie/develop/src/programs.rkt:62:0 bf [81] 90.8% ??? [86] 9.2% ---------------------------------------------------------------------- loop [37] 47.2% ??? [75] 52.8% [79] 636(2.4%) 0(0.0%) ??? ...ects/racket/contract/private/prop.rkt:519:4 ??? [85] 100.0% ---------------------------------------------------------------------- ??? [76] 100.0% [80] 4306(16.4%) 0(0.0%) create-quire1633 ...tposit-rkt/softposit.rkt:175:0 double->posit16 [89] 100.0% ---------------------------------------------------------------------- ->bf [78] 100.0% [81] 3602(13.7%) 832(3.2%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [98] 66.3% ??? [99] 10.6% ---------------------------------------------------------------------- ??? [77] 100.0% [82] 1638(6.2%) 1010(3.9%) ->flonum ...s/herbie/develop/src/programs.rkt:38:0 return/no-unsupplied [91] 20.0% ??? [92] 18.3% ---------------------------------------------------------------------- ??? [76] 100.0% [83] 1358(5.2%) 0(0.0%) big-posit16-mul .../src/syntax/softposit.rkt:110:0 bf* [90] 100.0% ---------------------------------------------------------------------- ??? [76] 100.0% [84] 750(2.9%) 0(0.0%) big-posit16-add .../src/syntax/softposit.rkt:108:0 ??? [94] 100.0% ---------------------------------------------------------------------- ??? [79] 100.0% [85] 636(2.4%) 636(2.4%) ??? ...lects/racket/contract/private/orc.rkt:83:14 ---------------------------------------------------------------------- ->bf [78] 100.0% [86] 366(1.4%) 366(1.4%) ??? ...ects/racket/contract/private/guts.rkt:746:8 ---------------------------------------------------------------------- ??? [76] 100.0% [87] 354(1.4%) 0(0.0%) big-posit16-sqrt ...src/syntax/softposit.rkt:112:0 ??? [94] 100.0% ---------------------------------------------------------------------- ??? [77] 100.0% [88] 352(1.3%) 352(1.3%) ??? ...-7.0/collects/racket/lazy-require.rkt:106:6 ---------------------------------------------------------------------- ??? [76] 9.7% create-quire1633 [80] 43.9% ??? [77] 46.4% [89] 9808(37.4%) 334(1.3%) double->posit16 ...ftposit-rkt/softposit.rkt:248:0 get-ffi-obj* [93] 96.6% ---------------------------------------------------------------------- big-posit16-mul [83] 100.0% [90] 1358(5.2%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [94] 100.0% ---------------------------------------------------------------------- ->flonum [82] 100.0% [91] 328(1.3%) 328(1.3%) return/no-unsupplied ...ct/private/arr-i.rkt:534:0 ---------------------------------------------------------------------- ->flonum [82] 100.0% [92] 300(1.1%) 300(1.1%) ??? ...s/racket/contract/private/arr-i.rkt:1252:29 ---------------------------------------------------------------------- double->posit16 [89] 100.0% [93] 9474(36.1%) 1616(6.2%) get-ffi-obj* ...-7.0/collects/ffi/unsafe.rkt:209:2 get-ffi-lib9 [95] 82.9% ---------------------------------------------------------------------- big-posit16-sqrt [87] 14.4% big-posit16-add [84] 30.5% bf* [90] 55.2% [94] 2462(9.4%) 1106(4.2%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [96] 55.1% ---------------------------------------------------------------------- get-ffi-obj* [93] 100.0% [95] 7858(30.0%) 2410(9.2%) get-ffi-lib9 ...-7.0/collects/ffi/unsafe.rkt:121:0 get-cross-lib-search-dirs [97] 57.0% loop [100] 12.3% ---------------------------------------------------------------------- ??? [94] 100.0% [96] 1356(5.2%) 0(0.0%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:821:4 ??? [99] 76.4% new-mpfr [98] 23.6% ---------------------------------------------------------------------- get-ffi-lib9 [95] 100.0% [97] 4482(17.1%) 4182(15.9%) get-cross-lib-search-dirs ...rivate/dirs.rkt:168:7 ??? [101] 6.7% ---------------------------------------------------------------------- bfmul [96] 11.8% bf [81] 88.2% [98] 2708(10.3%) 2708(10.3%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ---------------------------------------------------------------------- bf [81] 26.9% bfmul [96] 73.1% [99] 1418(5.4%) 1418(5.4%) ??? /opt/racket-7.0/collects/ffi/unsafe.rkt:898:4 ---------------------------------------------------------------------- get-ffi-lib9 [95] 100.0% [100] 966(3.7%) 966(3.7%) loop ...lects/ffi/../racket/private/map.rkt:149:23 ---------------------------------------------------------------------- get-cross-lib-search-dirs [97]100.0% [101] 300(1.1%) 300(1.1%) ??? ...racket-7.0/collects/racket/promise.rkt:42:2 ----------------------------------------------------------------------