Profiling results ----------------- Total cpu time observed: 19866ms (out of 20368ms) Number of samples taken: 50 (once every 397ms) ====================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ====================================================================== ??? [7] 100.0% [1] 19866(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [4] 100.0% ---------------------------------------------------------------------- ??? [7] 100.0% [2] 1062(5.3%) 0(0.0%) oracle-error ...erbie/develop/src/points.rkt:216:0 for-loop [5] 100.0% ---------------------------------------------------------------------- matcher [43] 100.0% [3] 318(1.6%) 0(0.0%) ??? .../herbie/develop/src/core/matcher.rkt:178:11 for-loop [6] 100.0% ---------------------------------------------------------------------- profile-thunk16 [1] 100.0% [4] 19866(100.0%) 0(0.0%) run ...et-7.0/share/pkgs/profile-lib/main.rkt:39:2 ??? [7] 100.0% ---------------------------------------------------------------------- oracle-error [2] 100.0% [5] 1062(5.3%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:217:2 map [41] 100.0% ---------------------------------------------------------------------- ??? [3] 100.0% [6] 318(1.6%) 0(0.0%) for-loop ...ie/develop/src/core/matcher.rkt:182:21 matcher [43] 100.0% ---------------------------------------------------------------------- run [4] 50.0% [7] 19866(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 50.0% loop [8] 40.9% oracle-error [2] 2.7% run-improve43 [9] 2.6% errors [27] 2.2% eval-errors [10] 1.6% ---------------------------------------------------------------------- ??? [7] 100.0% [8] 16260(81.8%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:320:2 loop [17] 57.1% loop [11] 37.1% filter-p&e [14] 3.8% make-exacts-halfpoints35 [16] 1.9% ---------------------------------------------------------------------- ??? [7] 100.0% [9] 1030(5.2%) 0(0.0%) run-improve43 ...ie/develop/src/mainloop.rkt:362:0 for-loop [12] 65.0% setup-prog!34 [15] 35.0% ---------------------------------------------------------------------- ??? [7] 100.0% [10] 628(3.2%) 0(0.0%) eval-errors ...herbie/develop/src/points.rkt:207:0 for-loop [13] 100.0% ---------------------------------------------------------------------- loop [8] 100.0% [11] 6038(30.4%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:275:2 make-exacts-walkup [18] 62.6% loop [17] 37.4% ---------------------------------------------------------------------- run-improve43 [9] 100.0% [12] 670(3.4%) 0(0.0%) for-loop .../herbie/develop/src/mainloop.rkt:377:4 run-iter! [19] 100.0% ---------------------------------------------------------------------- eval-errors [10] 100.0% [13] 628(3.2%) 326(1.6%) for-loop ...es/herbie/develop/src/points.rkt:209:2 ??? [49] 48.1% ---------------------------------------------------------------------- loop [8] 100.0% [14] 626(3.2%) 296(1.5%) filter-p&e .../herbie/develop/src/points.rkt:288:0 for-loop [21] 52.7% ---------------------------------------------------------------------- run-improve43 [9] 100.0% [15] 360(1.8%) 0(0.0%) setup-prog!34 ...bie/develop/src/mainloop.rkt:76:0 ??? [20] 100.0% ---------------------------------------------------------------------- loop [8] 100.0% [16] 310(1.6%) 0(0.0%) make-exacts-halfpoints35 ...p/src/points.rkt:273:0 make-exacts-walkup [18] 100.0% ---------------------------------------------------------------------- loop [11] 19.6% loop [8] 80.4% [17] 11544(58.1%) 0(0.0%) loop ...htlies/herbie/develop/src/points.rkt:258:4 loop [37] 100.0% ---------------------------------------------------------------------- make-exacts-halfpoints35 [16] 7.6% loop [11] 92.4% [18] 4090(20.6%) 0(0.0%) make-exacts-walkup ...develop/src/points.rkt:255:0 loop [37] 92.4% eval-prog [26] 7.6% ---------------------------------------------------------------------- for-loop [12] 100.0% [19] 670(3.4%) 0(0.0%) run-iter! ...herbie/develop/src/mainloop.rkt:343:0 localize! [23] 52.5% gen-rewrites! [25] 47.5% ---------------------------------------------------------------------- setup-prog!34 [15] 100.0% [20] 360(1.8%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:388:18 make-alt-table [22] 100.0% ---------------------------------------------------------------------- filter-p&e [14] 100.0% [21] 330(1.7%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:290:2 ordinary-value? [24] 100.0% ---------------------------------------------------------------------- ??? [20] 100.0% [22] 360(1.8%) 0(0.0%) make-alt-table ...elop/src/core/alt-table.rkt:33:0 errors [27] 100.0% ---------------------------------------------------------------------- run-iter! [19] 100.0% [23] 352(1.8%) 0(0.0%) localize! ...herbie/develop/src/mainloop.rkt:138:0 localize-error [28] 100.0% ---------------------------------------------------------------------- for-loop [21] 100.0% [24] 330(1.7%) 330(1.7%) ordinary-value? ...rbie/develop/src/float.rkt:54:0 ---------------------------------------------------------------------- run-iter! [19] 100.0% [25] 318(1.6%) 0(0.0%) gen-rewrites! ...ie/develop/src/mainloop.rkt:195:0 for-loop [29] 100.0% ---------------------------------------------------------------------- make-exacts-walkup [18] 100.0% [26] 310(1.6%) 0(0.0%) eval-prog ...herbie/develop/src/programs.rkt:131:0 inductor [30] 100.0% ---------------------------------------------------------------------- make-alt-table [22] 28.9% ??? [7] 71.1% [27] 1246(6.3%) 0(0.0%) errors ...lies/herbie/develop/src/points.rkt:232:0 for-loop [31] 100.0% ---------------------------------------------------------------------- localize! [23] 100.0% [28] 352(1.8%) 0(0.0%) localize-error ...velop/src/core/localize.rkt:52:0 hash-ref! [32] 100.0% ---------------------------------------------------------------------- gen-rewrites! [25] 100.0% [29] 318(1.6%) 0(0.0%) for-loop ...herbie/develop/src/mainloop.rkt:204:11 rewrite-expression-head20 [33] 100.0% ---------------------------------------------------------------------- eval-prog [26] 25.0% inductor [30] 75.0% [30] 310(1.6%) 310(1.6%) inductor .../herbie/develop/src/programs.rkt:136:4 inductor [30] 75.0% ---------------------------------------------------------------------- errors [27] 100.0% [31] 1246(6.3%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:235:4 ??? [49] 52.2% ??? [46] 47.8% ---------------------------------------------------------------------- loop [37] 33.3% map [41] 33.3% localize-error [28] 33.3% [32] 352(1.8%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 parse-loop15 [35] 66.7% f9 [34] 33.3% ---------------------------------------------------------------------- for-loop [29] 100.0% [33] 318(1.6%) 0(0.0%) rewrite-expression-head20 ...ore/matcher.rkt:128:0 rewriter [36] 100.0% ---------------------------------------------------------------------- hash-ref! [32] 100.0% [34] 352(1.8%) 0(0.0%) f9 ....0/collects/racket/match/compiler.rkt:507:40 map [41] 100.0% ---------------------------------------------------------------------- hash-ref! [32] 100.0% [35] 352(1.8%) 0(0.0%) parse-loop15 ...s/racket/match/compiler.rkt:418:15 loop [37] 100.0% ---------------------------------------------------------------------- rewrite-expression-head20 [33] 33.3% matcher [43] 66.7% [36] 318(1.6%) 0(0.0%) rewriter ...bie/develop/src/core/matcher.rkt:131:2 for-loop [39] 66.7% *rules* [38] 33.3% ---------------------------------------------------------------------- make-exacts-walkup [18] 0.0% parse-loop15 [35] 0.1% loop [17] 1.2% loop [37] 98.6% [37] 15676(78.9%) 0(0.0%) loop ...-7.0/collects/racket/private/map.rkt:40:19 loop [37] 98.6% ??? [49] 1.1% composed [40] 0.2% hash-ref! [32] 0.0% map [41] 0.0% ??? [46] 0.0% ??? [51] 0.0% ---------------------------------------------------------------------- rewriter [36] 100.0% [38] 318(1.6%) 0(0.0%) *rules* ...rbie/develop/src/syntax/rules.rkt:767:0 for-loop [42] 100.0% ---------------------------------------------------------------------- rewriter [36] 100.0% [39] 318(1.6%) 0(0.0%) for-loop ...ie/develop/src/core/matcher.rkt:134:10 matcher [43] 100.0% ---------------------------------------------------------------------- loop [37] 100.0% [40] 8684(43.7%) 0(0.0%) composed ...ollects/racket/private/list.rkt:321:16 ??? [46] 100.0% ---------------------------------------------------------------------- loop [37] 12.4% f9 [34] 12.4% for-loop [5] 75.1% [41] 1414(7.1%) 0(0.0%) map ...t-7.0/collects/racket/private/map.rkt:35:13 ??? [44] 75.1% hash-ref! [32] 12.4% ??? [49] 12.4% ---------------------------------------------------------------------- *rules* [38] 100.0% [42] 318(1.6%) 0(0.0%) for-loop ...ies/herbie/develop/src/common.rkt:71:9 ormap [45] 100.0% ---------------------------------------------------------------------- for-loop [6] 50.0% for-loop [39] 50.0% [43] 318(1.6%) 0(0.0%) matcher ...rbie/develop/src/core/matcher.rkt:166:2 ??? [3] 50.0% rewriter [36] 50.0% ---------------------------------------------------------------------- map [41] 100.0% [44] 1062(5.3%) 0(0.0%) ??? ...htlies/herbie/develop/src/points.rkt:218:26 ??? [46] 67.2% ??? [51] 32.8% ---------------------------------------------------------------------- for-loop [42] 100.0% [45] 318(1.6%) 0(0.0%) ormap ....0/collects/racket/private/map.rkt:141:13 flag-set? [47] 100.0% ---------------------------------------------------------------------- for-loop [31] 5.0% ??? [44] 6.0% loop [37] 15.8% composed [40] 73.1% [46] 11876(59.8%) 756(3.8%) ??? ...tlies/herbie/develop/src/programs.rkt:147:2 ->bf [48] 58.2% ??? [50] 27.4% ??? [49] 5.5% ??? [51] 2.5% ---------------------------------------------------------------------- ormap [45] 100.0% [47] 318(1.6%) 318(1.6%) flag-set? ...es/herbie/develop/src/config.rkt:28:0 ---------------------------------------------------------------------- ??? [46] 100.0% [48] 6914(34.8%) 796(4.0%) ->bf ...tlies/herbie/develop/src/programs.rkt:63:0 bf [52] 88.5% ---------------------------------------------------------------------- for-loop [13] 4.7% map [41] 5.5% for-loop [31] 10.2% ??? [46] 10.2% loop [37] 69.3% [49] 6368(32.1%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:357:33 double->posit16 [53] 58.0% ->flonum [54] 42.0% ---------------------------------------------------------------------- ??? [46] 100.0% [50] 3254(16.4%) 0(0.0%) ??? ...t-7.0/collects/racket/private/kw.rkt:592:14 big-posit16-mul [55] 65.0% big-posit16-add [57] 13.1% keyword-apply [58] 11.0% big-posit16-sqrt [59] 10.9% ---------------------------------------------------------------------- ??? [46] 30.2% loop [37] 34.8% ??? [44] 35.0% [51] 994(5.0%) 0(0.0%) ??? ...ects/racket/contract/private/prop.rkt:519:4 ??? [56] 100.0% ---------------------------------------------------------------------- ->bf [48] 100.0% [52] 6118(30.8%) 354(1.8%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [72] 81.4% ??? [73] 12.8% ---------------------------------------------------------------------- ??? [49] 100.0% [53] 3692(18.6%) 0(0.0%) double->posit16 ...ftposit-rkt/softposit.rkt:248:0 get-ffi-obj* [60] 100.0% ---------------------------------------------------------------------- ??? [49] 100.0% [54] 2676(13.5%) 1668(8.4%) ->flonum ...s/herbie/develop/src/programs.rkt:37:0 return/no-unsupplied [62] 26.6% ??? [64] 11.1% ---------------------------------------------------------------------- ??? [50] 100.0% [55] 2116(10.7%) 0(0.0%) big-posit16-mul .../src/syntax/softposit.rkt:110:0 bf* [61] 83.1% ...higher-order.rkt:361:33 [63] 16.9% ---------------------------------------------------------------------- ??? [51] 100.0% [56] 994(5.0%) 994(5.0%) ??? ...lects/racket/contract/private/orc.rkt:83:14 ---------------------------------------------------------------------- ??? [50] 100.0% [57] 426(2.1%) 0(0.0%) big-posit16-add .../src/syntax/softposit.rkt:108:0 ??? [66] 100.0% ---------------------------------------------------------------------- ??? [50] 100.0% [58] 358(1.8%) 358(1.8%) keyword-apply ...lects/racket/private/kw.rkt:421:2 ---------------------------------------------------------------------- ??? [50] 100.0% [59] 354(1.8%) 0(0.0%) big-posit16-sqrt ...src/syntax/softposit.rkt:112:0 ??? [66] 100.0% ---------------------------------------------------------------------- double->posit16 [53] 100.0% [60] 3692(18.6%) 0(0.0%) get-ffi-obj* ...-7.0/collects/ffi/unsafe.rkt:209:2 get-ffi-lib9 [65] 100.0% ---------------------------------------------------------------------- big-posit16-mul [55] 100.0% [61] 1758(8.8%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [66] 100.0% ---------------------------------------------------------------------- ->flonum [54] 100.0% [62] 712(3.6%) 712(3.6%) return/no-unsupplied ...ct/private/arr-i.rkt:534:0 ---------------------------------------------------------------------- big-posit16-mul [55] 100.0% [63] 358(1.8%) 0(0.0%) ...higher-order.rkt:361:33 (unknown source) ??? [67] 100.0% ---------------------------------------------------------------------- ->flonum [54] 100.0% [64] 296(1.5%) 296(1.5%) ??? ...s/racket/contract/private/arr-i.rkt:1252:29 ---------------------------------------------------------------------- get-ffi-obj* [60] 100.0% [65] 3692(18.6%) 1470(7.4%) get-ffi-lib9 ...-7.0/collects/ffi/unsafe.rkt:121:0 get-cross-lib-search-dirs [68] 60.2% ---------------------------------------------------------------------- big-posit16-sqrt [59] 13.9% big-posit16-add [57] 16.8% bf* [61] 69.3% [66] 2538(12.8%) 332(1.7%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [69] 73.0% bfsqrt [71] 13.9% ---------------------------------------------------------------------- ...higher-order.rkt:361:33 [63]100.0% [67] 358(1.8%) 0(0.0%) ??? ...cts/racket/contract/private/list.rkt:209:10 ??? [70] 100.0% ---------------------------------------------------------------------- get-ffi-lib9 [65] 100.0% [68] 2222(11.2%) 2222(11.2%) get-cross-lib-search-dirs ...rivate/dirs.rkt:168:7 ---------------------------------------------------------------------- ??? [66] 100.0% [69] 1852(9.3%) 0(0.0%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:821:4 ??? [73] 61.0% new-mpfr [72] 39.0% ---------------------------------------------------------------------- ??? [67] 100.0% [70] 358(1.8%) 358(1.8%) ??? ...ects/racket/contract/private/guts.rkt:746:8 ---------------------------------------------------------------------- ??? [66] 100.0% [71] 354(1.8%) 0(0.0%) bfsqrt ...lib/math/private/bigfloat/mpfr.rkt:618:4 ??? [73] 100.0% ---------------------------------------------------------------------- bfmul [69] 12.7% bf [52] 87.3% [72] 5700(28.7%) 5700(28.7%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ---------------------------------------------------------------------- bfsqrt [71] 15.6% bf [52] 34.6% bfmul [69] 49.8% [73] 2270(11.4%) 2270(11.4%) ??? /opt/racket-7.0/collects/ffi/unsafe.rkt:898:4 ----------------------------------------------------------------------