Profiling results ----------------- Total cpu time observed: 102866ms (out of 104428ms) Number of samples taken: 263 (once every 391ms) (Hiding functions with self<1.0% and local<2.0%: 3 of 82 hidden) ========================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ========================================================================== run [6] 50.0% [1] 102866(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [2] 50.0% loop [12] 47.3% run-improve39 [3] 1.9% eval-errors [4] 0.5% oracle-error [5] 0.2% -------------------------------------------------------------------------- ??? [1] 100.0% [2] 102866(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [6] 100.0% -------------------------------------------------------------------------- ??? [1] 100.0% [3] 3950(3.8%) 0(0.0%) run-improve39 ...ie/develop/src/mainloop.rkt:322:0 setup-prog!32 [7] 81.8% for-loop [9] 9.4% for-loop [10] 8.8% -------------------------------------------------------------------------- ??? [1] 100.0% [4] 974(0.9%) 0(0.0%) eval-errors ...herbie/develop/src/points.rkt:292:0 for-loop [8] 100.0% -------------------------------------------------------------------------- ??? [1] 100.0% [5] 312(0.3%) 0(0.0%) oracle-error ...erbie/develop/src/points.rkt:311:0 for-loop [11] 100.0% -------------------------------------------------------------------------- profile-thunk16 [2] 100.0% [6] 102866(100.0%) 0(0.0%) run ...racket/share/pkgs/profile-lib/main.rkt:39:2 ??? [1] 100.0% -------------------------------------------------------------------------- run-improve39 [3] 100.0% [7] 3232(3.1%) 0(0.0%) setup-prog!32 ...bie/develop/src/mainloop.rkt:63:0 loop [12] 100.0% -------------------------------------------------------------------------- eval-errors [4] 100.0% [8] 974(0.9%) 324(0.3%) for-loop ...es/herbie/develop/src/points.rkt:294:2 ??? [26] 66.7% -------------------------------------------------------------------------- run-improve39 [3] 100.0% [9] 370(0.4%) 0(0.0%) for-loop ...herbie/develop/src/mainloop.rkt:339:10 run-iter! [13] 100.0% -------------------------------------------------------------------------- run-improve39 [3] 100.0% [10] 348(0.3%) 0(0.0%) for-loop ...herbie/develop/src/mainloop.rkt:333:19 ??? [14] 100.0% -------------------------------------------------------------------------- oracle-error [5] 100.0% [11] 312(0.3%) 0(0.0%) for-loop ...es/herbie/develop/src/points.rkt:312:2 loop [27] 100.0% -------------------------------------------------------------------------- setup-prog!32 [7] 3.2% ??? [1] 96.8% [12] 100546(97.7%) 322(0.3%) loop ...htlies/herbie/develop/src/points.rkt:242:2 make-exacts-intervals [15] 99.7% -------------------------------------------------------------------------- for-loop [9] 100.0% [13] 370(0.4%) 0(0.0%) run-iter! ...herbie/develop/src/mainloop.rkt:303:0 localize-error [16] 100.0% -------------------------------------------------------------------------- for-loop [10] 100.0% [14] 348(0.3%) 0(0.0%) ??? ...s/herbie/develop/src/core/simplify.rkt:31:0 iterate-egraph!7 [17] 100.0% -------------------------------------------------------------------------- loop [12] 100.0% [15] 100224(97.4%) 0(0.0%) make-exacts-intervals ...elop/src/points.rkt:183:0 for-loop [18] 99.0% eval-prog [19] 0.6% loop [22] 0.4% -------------------------------------------------------------------------- run-iter! [13] 100.0% [16] 370(0.4%) 0(0.0%) localize-error ...velop/src/core/localize.rkt:51:0 hash-ref! [20] 100.0% -------------------------------------------------------------------------- ??? [14] 100.0% [17] 348(0.3%) 0(0.0%) iterate-egraph!7 ...lop/src/core/simplify.rkt:61:0 one-iter [21] 100.0% -------------------------------------------------------------------------- make-exacts-intervals [15] 100.0% [18] 99210(96.4%) 364(0.4%) for-loop ...es/herbie/develop/src/points.rkt:186:2 loop [22] 98.9% ??? [31] 0.3% -------------------------------------------------------------------------- make-exacts-intervals [15] 100.0% [19] 642(0.6%) 0(0.0%) eval-prog ...herbie/develop/src/programs.rkt:108:0 temp68_2 [25] 52.6% loop [74] 47.4% -------------------------------------------------------------------------- localize-error [16] 33.3% loop [27] 66.7% [20] 370(0.4%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 parse-loop14 [23] 100.0% -------------------------------------------------------------------------- iterate-egraph!7 [17] 100.0% [21] 348(0.3%) 0(0.0%) one-iter ...bie/develop/src/core/simplify.rkt:72:0 for-loop [24] 100.0% -------------------------------------------------------------------------- make-exacts-intervals [15] 0.4% for-loop [18] 99.6% [22] 98520(95.8%) 1308(1.3%) loop ...htlies/herbie/develop/src/points.rkt:168:2 ??? [26] 95.7% ??? [32] 2.6% ??? [41] 0.3% -------------------------------------------------------------------------- hash-ref! [20] 100.0% [23] 370(0.4%) 0(0.0%) parse-loop14 ...s/racket/match/compiler.rkt:418:15 loop [27] 100.0% -------------------------------------------------------------------------- for-loop [24] 50.0% one-iter [21] 50.0% [24] 348(0.3%) 348(0.3%) for-loop ...ie/develop/src/core/simplify.rkt:83:12 for-loop [24] 50.0% -------------------------------------------------------------------------- eval-prog [19] 100.0% [25] 338(0.3%) 0(0.0%) temp68_2 (unknown source) compile16 [28] 100.0% -------------------------------------------------------------------------- for-loop [8] 0.7% loop [22] 99.3% [26] 94960(92.3%) 0(0.0%) ??? ...tlies/herbie/develop/src/programs.rkt:124:2 ??? [29] 89.0% ??? [31] 10.7% ??? [32] 0.3% -------------------------------------------------------------------------- parse-loop14 [23] 2.2% for-loop [11] 31.3% loop [27] 66.5% [27] 998(1.0%) 686(0.7%) loop ...cket/collects/racket/private/map.rkt:40:19 loop [27] 66.5% ??? [32] 31.3% hash-ref! [20] 1.5% -------------------------------------------------------------------------- temp68_2 [25] 100.0% [28] 338(0.3%) 0(0.0%) compile16 (unknown source) temp74_0 [30] 100.0% -------------------------------------------------------------------------- ??? [26] 100.0% [29] 84492(82.1%) 376(0.4%) ??? ...acket/collects/racket/private/kw.rkt:592:14 ??? [31] 85.4% ??? [32] 14.1% -------------------------------------------------------------------------- compile16 [28] 100.0% [30] 338(0.3%) 0(0.0%) temp74_0 (unknown source) expand-single [33] 100.0% -------------------------------------------------------------------------- for-loop [18] 0.4% fold-binary-pred [51] 2.9% ??? [26] 12.0% ??? [29] 84.8% [31] 84406(82.1%) 3052(3.0%) ??? ...tract/private/arrow-higher-order.rkt:361:33 ival-fmod [34] 62.7% mk-ival [35] 17.9% ival-sinh [37] 11.0% ival-sub [38] 5.2% ??? [41] 0.4% -------------------------------------------------------------------------- loop [27] 2.1% ??? [26] 2.2% loop [22] 17.2% ??? [29] 78.6% [32] 15188(14.8%) 438(0.4%) ??? ...tract/private/arrow-higher-order.rkt:357:33 ival-pow [36] 73.6% ->flonum [39] 23.5% -------------------------------------------------------------------------- temp74_0 [30] 100.0% [33] 338(0.3%) 0(0.0%) expand-single (unknown source) expand-capturing-lifts [40] 100.0% -------------------------------------------------------------------------- ??? [31] 100.0% [34] 53568(52.1%) 5160(5.0%) ival-fmod ...bie/develop/src/biginterval.rkt:299:0 ival-div [42] 58.4% ival-fabs [44] 14.0% ival-mult [45] 8.9% ??? [63] 4.8% force [53] 2.3% fold-binary-pred [51] 1.2% ...higher-order.rkt:357:33 [55] 0.8% -------------------------------------------------------------------------- ??? [31] 100.0% [35] 15104(14.7%) 1866(1.8%) mk-ival ...herbie/develop/src/biginterval.rkt:52:0 bf [43] 87.6% -------------------------------------------------------------------------- ??? [32] 100.0% [36] 11182(10.9%) 1298(1.3%) ival-pow ...rbie/develop/src/biginterval.rkt:146:0 ??? [63] 69.5% fold-binary-pred [51] 16.0% ...higher-order.rkt:357:33 [55] 2.9% -------------------------------------------------------------------------- ??? [31] 100.0% [37] 9256(9.0%) 0(0.0%) ival-sinh ...bie/develop/src/biginterval.rkt:289:0 ??? [63] 100.0% -------------------------------------------------------------------------- ??? [31] 100.0% [38] 4386(4.3%) 1036(1.0%) ival-sub ...erbie/develop/src/biginterval.rkt:82:0 ??? [63] 60.6% ...higher-order.rkt:361:33 [60] 15.8% -------------------------------------------------------------------------- ??? [32] 100.0% [39] 3568(3.5%) 2310(2.2%) ->flonum ...s/herbie/develop/src/programs.rkt:36:0 return/no-unsupplied [46] 18.0% list-member? [48] 9.1% ??? [71] 8.1% -------------------------------------------------------------------------- loop [74] 47.4% expand-single [33] 52.6% [40] 642(0.6%) 0(0.0%) expand-capturing-lifts (unknown source) dispatch-transformer41 [47] 52.6% loop [74] 47.4% -------------------------------------------------------------------------- loop [22] 49.0% ??? [31] 51.0% [41] 604(0.6%) 296(0.3%) ??? ...ects/racket/contract/private/prop.rkt:519:4 ??? [49] 51.0% -------------------------------------------------------------------------- ival-fmod [34] 100.0% [42] 31274(30.4%) 2736(2.7%) ival-div ...rbie/develop/src/biginterval.rkt:105:0 ??? [63] 54.5% bfmin* [50] 20.4% foldl [59] 5.2% bfmax* [52] 4.9% ...higher-order.rkt:361:33 [60] 2.0% fold-binary-pred [51] 2.0% ...higher-order.rkt:357:33 [55] 1.1% -------------------------------------------------------------------------- mk-ival [35] 100.0% [43] 13238(12.9%) 298(0.3%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [70] 92.7% ??? [71] 5.0% -------------------------------------------------------------------------- ival-fmod [34] 100.0% [44] 7504(7.3%) 0(0.0%) ival-fabs ...bie/develop/src/biginterval.rkt:281:0 ??? [63] 48.9% force [53] 33.2% fold-binary-pred [51] 17.9% -------------------------------------------------------------------------- ival-fmod [34] 100.0% [45] 4758(4.6%) 376(0.4%) ival-mult ...rbie/develop/src/biginterval.rkt:94:0 bfmax* [52] 48.5% bf* [54] 29.0% bfmin* [50] 7.7% foldl [59] 6.9% -------------------------------------------------------------------------- ->flonum [39] 100.0% [46] 644(0.6%) 332(0.3%) return/no-unsupplied ...ct/private/arr-i.rkt:534:0 filter [57] 48.4% -------------------------------------------------------------------------- expand-capturing-lifts [40] 100.0% [47] 338(0.3%) 0(0.0%) dispatch-transformer41 (unknown source) apply-transformer52 [56] 100.0% -------------------------------------------------------------------------- ->flonum [39] 100.0% [48] 324(0.3%) 324(0.3%) list-member? ...llects/racket/private/set.rkt:24:0 -------------------------------------------------------------------------- ??? [41] 100.0% [49] 308(0.3%) 0(0.0%) ??? ...lects/racket/contract/private/orc.rkt:83:14 ??? [58] 100.0% -------------------------------------------------------------------------- ival-mult [45] 5.5% ival-div [42] 94.5% [50] 6744(6.6%) 0(0.0%) bfmin* .../herbie/develop/src/biginterval.rkt:88:0 foldl [59] 90.2% ...higher-order.rkt:361:33 [60] 9.8% -------------------------------------------------------------------------- ival-div [42] 14.4% ival-fmod [34] 14.5% ival-fabs [44] 30.5% ival-pow [36] 40.6% [51] 4392(4.3%) 1340(1.3%) fold-binary-pred ...gfloat/bigfloat-mpfr.rkt:117:0 ??? [31] 69.5% -------------------------------------------------------------------------- ival-div [42] 40.0% ival-mult [45] 60.0% [52] 3844(3.7%) 2206(2.1%) bfmax* .../herbie/develop/src/biginterval.rkt:91:0 foldl [59] 34.3% ...higher-order.rkt:361:33 [60] 8.3% -------------------------------------------------------------------------- ival-fmod [34] 33.4% ival-fabs [44] 66.6% [53] 3742(3.6%) 3742(3.6%) force ...collects/racket/private/promise.rkt:109:0 -------------------------------------------------------------------------- ival-mult [45] 100.0% [54] 1378(1.3%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [63] 100.0% -------------------------------------------------------------------------- ival-pow [36] 30.2% ival-div [42] 31.7% ival-fmod [34] 38.1% [55] 1086(1.1%) 344(0.3%) ...higher-order.rkt:357:33 (unknown source) ??? [61] 68.3% -------------------------------------------------------------------------- dispatch-transformer41 [47] 100.0% [56] 338(0.3%) 0(0.0%) apply-transformer52 (unknown source) apply-transformer-in-context [62]100.0% -------------------------------------------------------------------------- return/no-unsupplied [46] 100.0% [57] 312(0.3%) 312(0.3%) filter ...t/collects/racket/private/list.rkt:256:2 -------------------------------------------------------------------------- ??? [49] 100.0% [58] 308(0.3%) 308(0.3%) ??? ...ects/racket/contract/private/list.rkt:163:5 -------------------------------------------------------------------------- ival-mult [45] 3.5% bfmax* [52] 14.1% ival-div [42] 17.4% bfmin* [50] 65.0% [59] 9358(9.1%) 632(0.6%) foldl ...et/collects/racket/private/list.rkt:229:4 ??? [63] 93.2% -------------------------------------------------------------------------- bfmax* [52] 13.9% ival-div [42] 27.6% bfmin* [50] 28.5% ival-sub [38] 30.1% [60] 2308(2.2%) 1650(1.6%) ...higher-order.rkt:361:33 (unknown source) ??? [64] 28.5% -------------------------------------------------------------------------- ...higher-order.rkt:357:33 [55] 100.0% [61] 742(0.7%) 742(0.7%) ??? ...ects/racket/contract/private/guts.rkt:746:8 -------------------------------------------------------------------------- apply-transformer52 [56] 100.0% [62] 338(0.3%) 0(0.0%) apply-transformer-in-context (unknown source) parse-lambda [65] 100.0% -------------------------------------------------------------------------- bf* [54] 2.6% ival-fmod [34] 4.8% ival-sub [38] 5.0% ival-fabs [44] 6.9% ival-pow [36] 14.6% foldl [59] 16.4% ival-sinh [37] 17.4% ival-div [42] 32.1% [63] 53074(51.6%) 14150(13.8%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfdiv [66] 55.8% bfneg [67] 13.4% bftruncate [68] 4.1% -------------------------------------------------------------------------- ...higher-order.rkt:361:33 [60] 100.0% [64] 658(0.6%) 658(0.6%) ??? ...cts/racket/contract/private/list.rkt:209:10 -------------------------------------------------------------------------- apply-transformer-in-context [62]100.0% [65] 338(0.3%) 0(0.0%) parse-lambda ...llects/racket/private/kw.rkt:585:2 stx-pair? [69] 100.0% -------------------------------------------------------------------------- ??? [63] 100.0% [66] 29640(28.8%) 0(0.0%) bfdiv ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [70] 76.1% ??? [71] 23.9% -------------------------------------------------------------------------- ??? [63] 100.0% [67] 7108(6.9%) 0(0.0%) bfneg ...-lib/math/private/bigfloat/mpfr.rkt:618:4 new-mpfr [70] 64.7% ??? [71] 35.3% -------------------------------------------------------------------------- ??? [63] 100.0% [68] 2176(2.1%) 0(0.0%) bftruncate ...math/private/bigfloat/mpfr.rkt:716:4 new-mpfr [70] 100.0% -------------------------------------------------------------------------- parse-lambda [65] 100.0% [69] 338(0.3%) 0(0.0%) stx-pair? .../collects/racket/private/stx.rkt:40:4 syntax-e [72] 100.0% -------------------------------------------------------------------------- bftruncate [68] 5.2% bfneg [67] 11.0% bf [43] 29.5% bfdiv [66] 54.2% [70] 41608(40.4%) 41608(40.4%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 -------------------------------------------------------------------------- ->flonum [39] 2.7% bf [43] 6.3% bfneg [67] 23.8% bfdiv [66] 67.1% [71] 10546(10.3%) 10546(10.3%) ??? /home/p92/racket/collects/ffi/unsafe.rkt:898:4 -------------------------------------------------------------------------- stx-pair? [69] 100.0% [72] 338(0.3%) 0(0.0%) syntax-e (unknown source) syntax-e/no-taint [73] 100.0% -------------------------------------------------------------------------- syntax-e [72] 100.0% [73] 338(0.3%) 0(0.0%) syntax-e/no-taint (unknown source) loop [74] 100.0% -------------------------------------------------------------------------- loop [74] 15.8% expand-capturing-lifts [40] 15.8% eval-prog [19] 15.8% syntax-e/no-taint [73] 52.6% [74] 642(0.6%) 0(0.0%) loop (unknown source) gf [75] 52.6% expand-capturing-lifts [40] 15.8% loop [74] 15.8% for-loop [76] 15.8% -------------------------------------------------------------------------- loop [74] 100.0% [75] 338(0.3%) 338(0.3%) gf (unknown source) -------------------------------------------------------------------------- run-module-instance!125 [78] 50.0% loop [74] 50.0% [76] 304(0.3%) 0(0.0%) for-loop (unknown source) run-module-instance!125 [78] 50.0% [running body] [77] 50.0% -------------------------------------------------------------------------- for-loop [76] 100.0% [77] 304(0.3%) 0(0.0%) [running body] ...et/collects/racket/dict.rkt":##f do-partial-app [79] 100.0% -------------------------------------------------------------------------- for-loop [76] 100.0% [78] 304(0.3%) 0(0.0%) run-module-instance!125 (unknown source) for-loop [76] 100.0% -------------------------------------------------------------------------- [running body] [77] 100.0% [79] 304(0.3%) 304(0.3%) do-partial-app ...ntract/private/provide.rkt:431:0 --------------------------------------------------------------------------