Profiling results ----------------- Total cpu time observed: 45860ms (out of 46684ms) Number of samples taken: 128 (once every 358ms) (Hiding functions with self<1.0% and local<2.0%: 2 of 97 hidden) ========================================================================= Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ========================================================================= run [6] 50.0% [1] 45860(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [3] 50.0% loop [10] 40.9% run-improve39 [4] 9.1% ------------------------------------------------------------------------- hash-ref! [21] 100.0% [2] 670(1.5%) 0(0.0%) f8 ...et/collects/racket/match/compiler.rkt:507:40 map [5] 100.0% ------------------------------------------------------------------------- ??? [1] 100.0% [3] 45860(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [6] 100.0% ------------------------------------------------------------------------- ??? [1] 100.0% [4] 8382(18.3%) 0(0.0%) run-improve39 ...al-sampler/src/mainloop.rkt:287:0 for-loop [7] 88.0% setup-prog!32 [8] 7.9% for-loop [9] 4.1% ------------------------------------------------------------------------- loop [29] 23.3% f8 [2] 76.7% [5] 670(1.5%) 0(0.0%) map ...acket/collects/racket/private/map.rkt:35:13 hash-ref! [21] 76.7% ??? [57] 23.3% ------------------------------------------------------------------------- profile-thunk16 [3] 100.0% [6] 45860(100.0%) 0(0.0%) run ...racket/share/pkgs/profile-lib/main.rkt:39:2 ??? [1] 100.0% ------------------------------------------------------------------------- run-improve39 [4] 100.0% [7] 7374(16.1%) 0(0.0%) for-loop ...nterval-sampler/src/mainloop.rkt:304:8 run-iter! [11] 100.0% ------------------------------------------------------------------------- run-improve39 [4] 100.0% [8] 662(1.4%) 0(0.0%) setup-prog!32 ...val-sampler/src/mainloop.rkt:63:0 loop [10] 100.0% ------------------------------------------------------------------------- run-improve39 [4] 100.0% [9] 346(0.8%) 0(0.0%) for-loop ...terval-sampler/src/mainloop.rkt:299:29 ??? [12] 100.0% ------------------------------------------------------------------------- setup-prog!32 [8] 1.7% ??? [1] 98.3% [10] 38140(83.2%) 0(0.0%) loop ...e/biginterval-sampler/src/points.rkt:241:2 make-exacts-intervals [13] 97.4% for-loop [17] 1.8% debug-print [25] 0.8% ------------------------------------------------------------------------- for-loop [7] 100.0% [11] 7374(16.1%) 0(0.0%) run-iter! ...terval-sampler/src/mainloop.rkt:268:0 finalize-iter! [14] 81.7% simplify! [15] 9.2% localize-error [16] 9.1% ------------------------------------------------------------------------- for-loop [9] 100.0% [12] 346(0.8%) 0(0.0%) ??? ...interval-sampler/src/core/simplify.rkt:31:0 iterate-egraph!7 [18] 100.0% ------------------------------------------------------------------------- loop [10] 100.0% [13] 37156(81.0%) 0(0.0%) make-exacts-intervals ...pler/src/points.rkt:182:0 for-loop [19] 99.1% eval-prog [48] 0.9% ------------------------------------------------------------------------- run-iter! [11] 100.0% [14] 6026(13.1%) 0(0.0%) finalize-iter! ...l-sampler/src/mainloop.rkt:222:0 ??? [28] 100.0% ------------------------------------------------------------------------- run-iter! [11] 100.0% [15] 678(1.5%) 0(0.0%) simplify! ...terval-sampler/src/mainloop.rkt:182:0 for-loop [20] 100.0% ------------------------------------------------------------------------- run-iter! [11] 100.0% [16] 670(1.5%) 0(0.0%) localize-error ...mpler/src/core/localize.rkt:51:0 hash-ref! [21] 100.0% ------------------------------------------------------------------------- loop [10] 100.0% [17] 668(1.5%) 0(0.0%) for-loop ...interval-sampler/src/points.rkt:256:19 for-loop [22] 100.0% ------------------------------------------------------------------------- ??? [12] 100.0% [18] 346(0.8%) 0(0.0%) iterate-egraph!7 ...ler/src/core/simplify.rkt:61:0 one-iter [23] 100.0% ------------------------------------------------------------------------- make-exacts-intervals [13] 100.0% [19] 36804(80.3%) 662(1.4%) for-loop ...ginterval-sampler/src/points.rkt:185:2 loop [24] 98.2% ------------------------------------------------------------------------- simplify! [15] 100.0% [20] 678(1.5%) 310(0.7%) for-loop ...nterval-sampler/src/mainloop.rkt:186:6 debug-print [25] 54.3% ------------------------------------------------------------------------- loop [29] 33.3% map [5] 33.3% localize-error [16] 33.3% [21] 670(1.5%) 0(0.0%) hash-ref! .../racket/private/more-scheme.rkt:376:2 parse-loop14 [26] 66.7% f8 [2] 33.3% ------------------------------------------------------------------------- for-loop [17] 100.0% [22] 668(1.5%) 346(0.8%) for-loop ...ginterval-sampler/src/points.rkt:233:4 ??? [57] 48.2% ------------------------------------------------------------------------- iterate-egraph!7 [18] 100.0% [23] 346(0.8%) 0(0.0%) one-iter ...val-sampler/src/core/simplify.rkt:72:0 for-loop [27] 100.0% ------------------------------------------------------------------------- for-loop [19] 100.0% [24] 36142(78.8%) 1888(4.1%) loop ...e/biginterval-sampler/src/points.rkt:167:2 ??? [50] 89.5% ??? [57] 4.5% ??? [28] 0.8% ------------------------------------------------------------------------- loop [10] 46.2% for-loop [20] 53.8% [25] 684(1.5%) 0(0.0%) debug-print ...nterval-sampler/src/debug.rkt:107:0 for-loop [30] 53.8% ??? [33] 46.2% ------------------------------------------------------------------------- hash-ref! [21] 100.0% [26] 670(1.5%) 0(0.0%) parse-loop14 ...s/racket/match/compiler.rkt:418:15 loop [29] 73.3% loop [31] 26.7% ------------------------------------------------------------------------- for-loop [27] 50.0% one-iter [23] 50.0% [27] 346(0.8%) 0(0.0%) for-loop ...al-sampler/src/core/simplify.rkt:83:12 for-loop [27] 50.0% match-e [32] 50.0% ------------------------------------------------------------------------- loop [24] 4.8% finalize-iter! [14] 95.2% [28] 6328(13.8%) 302(0.7%) ??? ...contract/private/arrow-val-first.rkt:388:18 for-loop [34] 95.2% ------------------------------------------------------------------------- loop [29] 45.8% parse-loop14 [26] 54.2% [29] 670(1.5%) 0(0.0%) loop ...cket/collects/racket/private/map.rkt:40:19 hash-ref! [21] 53.8% loop [29] 45.8% map [5] 0.4% ------------------------------------------------------------------------- debug-print [25] 100.0% [30] 368(0.8%) 368(0.8%) for-loop ...iginterval-sampler/src/debug.rkt:115:2 ------------------------------------------------------------------------- parse-loop14 [26] 2.5% loop [31] 97.5% [31] 358(0.8%) 358(0.8%) loop ...cket/collects/racket/private/map.rkt:54:19 loop [31] 97.5% ------------------------------------------------------------------------- for-loop [27] 100.0% [32] 346(0.8%) 346(0.8%) match-e ...terval-sampler/src/core/ematch.rkt:39:0 ------------------------------------------------------------------------- debug-print [25] 100.0% [33] 316(0.7%) 0(0.0%) ??? .../contract/private/arrow-val-first.rkt:428:3 mk-call [35] 100.0% ------------------------------------------------------------------------- ??? [28] 100.0% [34] 6026(13.1%) 0(0.0%) for-loop ...al-sampler/src/core/alt-table.rkt:51:2 atab-add-altn [36] 89.7% loop [37] 10.3% ------------------------------------------------------------------------- ??? [33] 100.0% [35] 316(0.7%) 0(0.0%) mk-call ...tract/private/arrow-val-first.rkt:431:5 loop [38] 100.0% ------------------------------------------------------------------------- for-loop [34] 100.0% [36] 5408(11.8%) 0(0.0%) atab-add-altn ...pler/src/core/alt-table.rkt:210:0 override-at-pnts [39] 53.7% best-and-tied-at-points [40] 46.3% ------------------------------------------------------------------------- for-loop [34] 100.0% [37] 618(1.3%) 0(0.0%) loop ...erval-sampler/src/core/alt-table.rkt:184:2 loop [41] 50.2% argmax [42] 49.8% ------------------------------------------------------------------------- mk-call [35] 100.0% [38] 316(0.7%) 0(0.0%) loop ...contract/private/arrow-val-first.rkt:435:8 ??? [55] 100.0% ------------------------------------------------------------------------- atab-add-altn [36] 100.0% [39] 2902(6.3%) 0(0.0%) override-at-pnts ...r/src/core/alt-table.rkt:145:0 errors [43] 88.1% for-loop [44] 11.9% ------------------------------------------------------------------------- atab-add-altn [36] 100.0% [40] 2506(5.5%) 0(0.0%) best-and-tied-at-points ...ore/alt-table.rkt:122:0 errors [43] 87.5% for-loop [45] 12.5% ------------------------------------------------------------------------- loop [37] 25.0% loop [41] 75.0% [41] 310(0.7%) 310(0.7%) loop ...p92/racket/collects/racket/list.rkt:434:28 loop [41] 75.0% ------------------------------------------------------------------------- loop [37] 100.0% [42] 308(0.7%) 0(0.0%) argmax ...92/racket/collects/racket/list.rkt:766:0 for-loop [46] 100.0% ------------------------------------------------------------------------- best-and-tied-at-points [40] 46.2% override-at-pnts [39] 53.8% [43] 4752(10.4%) 0(0.0%) errors ...biginterval-sampler/src/points.rkt:282:0 for-loop [47] 73.8% eval-prog [48] 26.2% ------------------------------------------------------------------------- override-at-pnts [39] 100.0% [44] 344(0.8%) 344(0.8%) for-loop ...-sampler/src/core/alt-table.rkt:146:43 ------------------------------------------------------------------------- best-and-tied-at-points [40] 100.0% [45] 312(0.7%) 312(0.7%) for-loop ...-sampler/src/core/alt-table.rkt:124:16 ------------------------------------------------------------------------- argmax [42] 100.0% [46] 308(0.7%) 0(0.0%) for-loop ...nterval-sampler/src/programs.rkt:183:2 ...mpler/src/common.rkt:48:2 [49]100.0% ------------------------------------------------------------------------- errors [43] 100.0% [47] 3508(7.6%) 302(0.7%) for-loop ...ginterval-sampler/src/points.rkt:285:4 ??? [50] 82.5% ulp-difference [52] 8.9% ------------------------------------------------------------------------- make-exacts-intervals [13] 22.1% errors [43] 77.9% [48] 1596(3.5%) 352(0.8%) eval-prog ...terval-sampler/src/programs.rkt:108:0 temp68_2 [51] 77.9% ------------------------------------------------------------------------- for-loop [46] 100.0% [49] 308(0.7%) 308(0.7%) ...mpler/src/common.rkt:48:2 ...e/arr-i.rkt:918:19 ------------------------------------------------------------------------- for-loop [47] 8.2% loop [24] 91.8% [50] 35232(76.8%) 0(0.0%) ??? .../biginterval-sampler/src/programs.rkt:124:2 ??? [53] 77.7% ??? [56] 21.3% ??? [55] 1.0% ------------------------------------------------------------------------- eval-prog [48] 100.0% [51] 1244(2.7%) 0(0.0%) temp68_2 (unknown source) compile16 [54] 100.0% ------------------------------------------------------------------------- for-loop [47] 100.0% [52] 312(0.7%) 312(0.7%) ulp-difference ...erval-sampler/src/float.rkt:19:0 ------------------------------------------------------------------------- ??? [50] 100.0% [53] 27386(59.7%) 2238(4.9%) ??? ...acket/collects/racket/private/kw.rkt:592:14 ??? [56] 56.2% ??? [57] 31.1% ??? [59] 3.4% ------------------------------------------------------------------------- temp68_2 [51] 100.0% [54] 1244(2.7%) 0(0.0%) compile16 (unknown source) temp74_0 [58] 100.0% ------------------------------------------------------------------------- loop [38] 48.5% ??? [50] 51.5% [55] 652(1.4%) 336(0.7%) ??? ...ects/racket/contract/private/prop.rkt:519:4 ??? [60] 48.5% ------------------------------------------------------------------------- fold-binary-pred [72] 4.7% ??? [50] 31.5% ??? [53] 63.8% [56] 23872(52.1%) 1934(4.2%) ??? ...tract/private/arrow-higher-order.rkt:361:33 mk-ival [62] 31.5% ival-sin [63] 30.3% ival-atan2 [64] 15.7% ival-sub [65] 8.2% ival-sqrt [67] 6.9% ------------------------------------------------------------------------- map [5] 2.9% for-loop [22] 3.0% loop [24] 15.0% ??? [53] 79.1% [57] 10752(23.4%) 322(0.7%) ??? ...tract/private/arrow-higher-order.rkt:357:33 ival-pow [61] 79.1% ->flonum [66] 17.9% ------------------------------------------------------------------------- compile16 [54] 100.0% [58] 1244(2.7%) 0(0.0%) temp74_0 (unknown source) expand-single [68] 100.0% ------------------------------------------------------------------------- ??? [53] 100.0% [59] 932(2.0%) 324(0.7%) ??? ...terval-sampler/src/syntax/syntax.rkt:196:17 ??? [74] 65.2% ------------------------------------------------------------------------- ??? [55] 100.0% [60] 316(0.7%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:681:0 contract-first-order [69] 100.0% ------------------------------------------------------------------------- ??? [57] 100.0% [61] 8504(18.5%) 1988(4.3%) ival-pow ...rval-sampler/src/biginterval.rkt:145:0 ??? [70] 48.5% fold-binary-pred [72] 11.5% bfodd? [75] 7.8% ...higher-order.rkt:361:33 [76] 4.8% ...higher-order.rkt:357:33 [77] 4.1% ------------------------------------------------------------------------- ??? [56] 100.0% [62] 7510(16.4%) 658(1.4%) mk-ival ...terval-sampler/src/biginterval.rkt:51:0 bf [71] 91.2% ------------------------------------------------------------------------- ??? [56] 100.0% [63] 7380(16.1%) 704(1.5%) ival-sin ...rval-sampler/src/biginterval.rkt:220:0 ??? [70] 73.7% bfodd? [75] 4.4% ??? [88] 4.2% force [78] 4.1% fold-binary-pred [72] 4.1% ------------------------------------------------------------------------- ??? [56] 100.0% [64] 3742(8.2%) 294(0.6%) ival-atan2 ...al-sampler/src/biginterval.rkt:241:0 ??? [70] 92.1% ------------------------------------------------------------------------- ??? [56] 100.0% [65] 1960(4.3%) 914(2.0%) ival-sub ...erval-sampler/src/biginterval.rkt:81:0 ??? [70] 35.3% ...higher-order.rkt:361:33 [76] 18.1% ------------------------------------------------------------------------- ??? [57] 100.0% [66] 1926(4.2%) 1310(2.9%) ->flonum ...interval-sampler/src/programs.rkt:36:0 ??? [74] 32.0% ------------------------------------------------------------------------- ??? [56] 100.0% [67] 1648(3.6%) 0(0.0%) ival-sqrt ...val-sampler/src/biginterval.rkt:133:0 ??? [70] 100.0% ------------------------------------------------------------------------- temp74_0 [58] 100.0% [68] 1244(2.7%) 0(0.0%) expand-single (unknown source) expand-capturing-lifts [73] 100.0% ------------------------------------------------------------------------- ??? [60] 100.0% [69] 316(0.7%) 316(0.7%) contract-first-order ...act/private/guts.rkt:216:0 ------------------------------------------------------------------------- ival-sub [65] 4.5% ival-sqrt [67] 10.7% ival-atan2 [64] 22.5% ival-pow [61] 26.9% ival-sin [63] 35.4% [70] 15348(33.5%) 3406(7.4%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfsub [79] 51.9% bfsqrt [80] 12.8% bffloor [82] 6.8% pi.bf [83] 6.3% ------------------------------------------------------------------------- mk-ival [62] 100.0% [71] 6852(14.9%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [85] 86.5% ??? [86] 13.5% ------------------------------------------------------------------------- ival-sin [63] 23.6% ival-pow [61] 76.4% [72] 1278(2.8%) 0(0.0%) fold-binary-pred ...gfloat/bigfloat-mpfr.rkt:117:0 ??? [56] 100.0% ------------------------------------------------------------------------- expand-single [68] 100.0% [73] 1244(2.7%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [81] 100.0% ------------------------------------------------------------------------- ??? [59] 49.7% ->flonum [66] 50.3% [74] 1224(2.7%) 1224(2.7%) ??? ...s/racket/contract/private/arr-i.rkt:1252:29 ------------------------------------------------------------------------- ival-sin [63] 32.8% ival-pow [61] 67.2% [75] 982(2.1%) 346(0.8%) bfodd? ...lib/math/private/bigfloat/mpfr.rkt:803:0 ??? [86] 64.8% ------------------------------------------------------------------------- ival-sub [65] 46.5% ival-pow [61] 53.5% [76] 762(1.7%) 408(0.9%) ...higher-order.rkt:361:33 (unknown source) ??? [84] 46.5% ------------------------------------------------------------------------- ival-pow [61] 100.0% [77] 350(0.8%) 350(0.8%) ...higher-order.rkt:357:33 (unknown source) ------------------------------------------------------------------------- ival-sin [63] 100.0% [78] 304(0.7%) 304(0.7%) force ...collects/racket/private/promise.rkt:109:0 ------------------------------------------------------------------------- ??? [70] 100.0% [79] 7964(17.4%) 0(0.0%) bfsub ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [85] 71.0% ??? [86] 29.0% ------------------------------------------------------------------------- ??? [70] 100.0% [80] 1966(4.3%) 0(0.0%) bfsqrt ...lib/math/private/bigfloat/mpfr.rkt:618:4 new-mpfr [85] 67.0% ??? [86] 33.0% ------------------------------------------------------------------------- expand-capturing-lifts [73] 100.0% [81] 1244(2.7%) 0(0.0%) lambda-clause-expander (unknown source) finish-bodys [87] 100.0% ------------------------------------------------------------------------- ??? [70] 100.0% [82] 1042(2.3%) 0(0.0%) bffloor ...ib/math/private/bigfloat/mpfr.rkt:716:4 ??? [86] 63.0% new-mpfr [85] 37.0% ------------------------------------------------------------------------- ??? [70] 100.0% [83] 970(2.1%) 0(0.0%) pi.bf ...-lib/math/private/bigfloat/mpfr.rkt:998:4 new-mpfr [85] 69.7% ??? [86] 30.3% ------------------------------------------------------------------------- ...higher-order.rkt:361:33 [76] 100.0% [84] 354(0.8%) 0(0.0%) ??? ...cts/racket/contract/private/list.rkt:209:10 ??? [88] 100.0% ------------------------------------------------------------------------- bffloor [82] 2.8% pi.bf [83] 4.8% bfsqrt [80] 9.4% bfsub [79] 40.5% bf [71] 42.5% [85] 13966(30.5%) 13966(30.5%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ------------------------------------------------------------------------- pi.bf [83] 5.4% bfodd? [75] 11.6% bfsqrt [80] 11.9% bffloor [82] 12.0% bf [71] 16.9% bfsub [79] 42.2% [86] 5464(11.9%) 5464(11.9%) ??? /home/p92/racket/collects/ffi/unsafe.rkt:898:4 ------------------------------------------------------------------------- lambda-clause-expander [81] 15.6% for-loop [89] 84.4% [87] 1244(2.7%) 0(0.0%) finish-bodys (unknown source) for-loop [89] 100.0% ------------------------------------------------------------------------- ival-sin [63] 46.7% ??? [84] 53.3% [88] 664(1.4%) 664(1.4%) ??? ...ects/racket/contract/private/guts.rkt:746:8 ------------------------------------------------------------------------- for-loop [89] 13.1% finish-bodys [87] 86.9% [89] 1244(2.7%) 0(0.0%) for-loop (unknown source) finish-bodys [87] 73.7% for-loop [89] 13.1% syntax-e [90] 11.3% ------------------------------------------------------------------------- for-loop [89] 100.0% [90] 922(2.0%) 0(0.0%) syntax-e (unknown source) syntax-e/no-taint [91] 100.0% ------------------------------------------------------------------------- syntax-e [90] 100.0% [91] 922(2.0%) 0(0.0%) syntax-e/no-taint (unknown source) loop [92] 100.0% ------------------------------------------------------------------------- syntax-e/no-taint [91] 100.0% [92] 922(2.0%) 310(0.7%) loop (unknown source) gf [93] 66.4% ------------------------------------------------------------------------- loop [92] 100.0% [93] 612(1.3%) 0(0.0%) gf (unknown source) propagation-apply [94] 50.7% propagation-apply-shifted [95] 49.3% ------------------------------------------------------------------------- gf [93] 100.0% [94] 310(0.7%) 310(0.7%) propagation-apply (unknown source) ------------------------------------------------------------------------- gf [93] 100.0% [95] 302(0.7%) 302(0.7%) propagation-apply-shifted (unknown source) -------------------------------------------------------------------------