Profiling results ----------------- Total cpu time observed: 19272ms (out of 19892ms) Number of samples taken: 52 (once every 371ms) ======================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ======================================================================== run [7] 50.0% [1] 19272(100.0%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [3] 50.0% loop [4] 43.3% run-improve39 [5] 6.7% ------------------------------------------------------------------------ loop [86] 100.0% [2] 348(1.8%) 0(0.0%) dispatch-transformer41 (unknown source) apply-transformer52 [6] 100.0% ------------------------------------------------------------------------ ??? [1] 100.0% [3] 19272(100.0%) 0(0.0%) profile-thunk16 ...e/pkgs/profile-lib/main.rkt:9:0 run [7] 100.0% ------------------------------------------------------------------------ ??? [1] 100.0% [4] 16674(86.5%) 0(0.0%) loop ...e/biginterval-sampler/src/points.rkt:220:2 make-exacts-intervals [8] 94.0% for-loop [10] 6.0% ------------------------------------------------------------------------ ??? [1] 100.0% [5] 2598(13.5%) 0(0.0%) run-improve39 ...al-sampler/src/mainloop.rkt:287:0 for-loop [9] 100.0% ------------------------------------------------------------------------ dispatch-transformer41 [2] 100.0% [6] 348(1.8%) 0(0.0%) apply-transformer52 (unknown source) loop [86] 100.0% ------------------------------------------------------------------------ profile-thunk16 [3] 100.0% [7] 19272(100.0%) 0(0.0%) run ...racket/share/pkgs/profile-lib/main.rkt:39:2 ??? [1] 100.0% ------------------------------------------------------------------------ loop [4] 100.0% [8] 15680(81.4%) 0(0.0%) make-exacts-intervals ...pler/src/points.rkt:161:0 for-loop [11] 100.0% ------------------------------------------------------------------------ run-improve39 [5] 100.0% [9] 2598(13.5%) 0(0.0%) for-loop ...nterval-sampler/src/mainloop.rkt:304:8 run-iter! [12] 100.0% ------------------------------------------------------------------------ loop [4] 100.0% [10] 994(5.2%) 0(0.0%) for-loop ...interval-sampler/src/points.rkt:235:19 for-loop [13] 100.0% ------------------------------------------------------------------------ make-exacts-intervals [8] 100.0% [11] 15680(81.4%) 0(0.0%) for-loop ...ginterval-sampler/src/points.rkt:164:2 loop [14] 97.3% ??? [29] 2.7% ------------------------------------------------------------------------ for-loop [9] 100.0% [12] 2598(13.5%) 0(0.0%) run-iter! ...terval-sampler/src/mainloop.rkt:268:0 finalize-iter! [15] 51.7% simplify! [16] 25.8% gen-rewrites! [17] 11.5% choose-best-alt! [18] 10.9% ------------------------------------------------------------------------ for-loop [10] 100.0% [13] 994(5.2%) 0(0.0%) for-loop ...ginterval-sampler/src/points.rkt:212:4 ??? [29] 100.0% ------------------------------------------------------------------------ for-loop [11] 100.0% [14] 15260(79.2%) 398(2.1%) loop ...e/biginterval-sampler/src/points.rkt:144:2 ??? [19] 90.6% ??? [29] 6.8% ------------------------------------------------------------------------ run-iter! [12] 100.0% [15] 1344(7.0%) 0(0.0%) finalize-iter! ...l-sampler/src/mainloop.rkt:222:0 ??? [20] 100.0% ------------------------------------------------------------------------ run-iter! [12] 100.0% [16] 670(3.5%) 0(0.0%) simplify! ...terval-sampler/src/mainloop.rkt:182:0 for-loop [21] 100.0% ------------------------------------------------------------------------ run-iter! [12] 100.0% [17] 300(1.6%) 0(0.0%) gen-rewrites! ...al-sampler/src/mainloop.rkt:165:0 for-loop [22] 100.0% ------------------------------------------------------------------------ run-iter! [12] 100.0% [18] 284(1.5%) 0(0.0%) choose-best-alt! ...sampler/src/mainloop.rkt:114:0 ??? [20] 100.0% ------------------------------------------------------------------------ for-loop [57] 2.5% loop [14] 97.5% [19] 14178(73.6%) 656(3.4%) ??? .../biginterval-sampler/src/programs.rkt:124:2 ??? [23] 83.8% ??? [28] 11.6% ------------------------------------------------------------------------ choose-best-alt! [18] 17.4% finalize-iter! [15] 82.6% [20] 1628(8.4%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:388:18 for-loop [24] 82.6% ??? [27] 17.4% ------------------------------------------------------------------------ simplify! [16] 100.0% [21] 670(3.5%) 0(0.0%) for-loop ...nterval-sampler/src/mainloop.rkt:186:6 for-loop [25] 100.0% ------------------------------------------------------------------------ gen-rewrites! [17] 100.0% [22] 300(1.6%) 0(0.0%) for-loop ...terval-sampler/src/mainloop.rkt:170:11 ??? [26] 100.0% ------------------------------------------------------------------------ ??? [19] 100.0% [23] 11876(61.6%) 302(1.6%) ??? ...acket/collects/racket/private/kw.rkt:592:14 ??? [28] 94.4% ??? [31] 3.0% ------------------------------------------------------------------------ ??? [20] 100.0% [24] 1344(7.0%) 0(0.0%) for-loop ...al-sampler/src/core/alt-table.rkt:51:2 atab-add-altn [30] 100.0% ------------------------------------------------------------------------ for-loop [21] 100.0% [25] 670(3.5%) 0(0.0%) for-loop ...nterval-sampler/src/mainloop.rkt:210:8 ??? [29] 100.0% ------------------------------------------------------------------------ for-loop [22] 100.0% [26] 300(1.6%) 0(0.0%) ??? ...iginterval-sampler/src/alternative.rkt:60:0 rewriter [32] 100.0% ------------------------------------------------------------------------ ??? [20] 100.0% [27] 284(1.5%) 0(0.0%) ??? ...nterval-sampler/src/core/alt-table.rkt:54:0 ??? [29] 100.0% ------------------------------------------------------------------------ ??? [19] 12.8% ??? [23] 87.2% [28] 12862(66.7%) 358(1.9%) ??? ...tract/private/arrow-higher-order.rkt:361:33 ival-mult [33] 81.8% mk-ival [35] 10.0% ival-sub [38] 5.4% ------------------------------------------------------------------------ ??? [27] 8.3% location-do [39] 9.8% for-loop [25] 9.8% for-loop [11] 12.3% for-loop [13] 29.1% loop [14] 30.6% [29] 3410(17.7%) 300(1.6%) ??? ...tract/private/arrow-higher-order.rkt:357:33 ->flonum [34] 42.9% sample-multi-bounded [37] 20.4% location-do [39] 9.8% ??? [40] 9.8% best-alt [43] 8.3% ------------------------------------------------------------------------ for-loop [24] 100.0% [30] 1344(7.0%) 0(0.0%) atab-add-altn ...pler/src/core/alt-table.rkt:210:0 override-at-pnts [36] 52.5% best-and-tied-at-points [41] 47.5% ------------------------------------------------------------------------ ??? [23] 100.0% [31] 358(1.9%) 358(1.9%) ??? ...rivate/unconstrained-domain-arrow.rkt:40:13 ------------------------------------------------------------------------ matcher [51] 50.0% ??? [26] 50.0% [32] 300(1.6%) 0(0.0%) rewriter ...val-sampler/src/core/matcher.rkt:131:2 for-loop [42] 100.0% ------------------------------------------------------------------------ ??? [28] 100.0% [33] 10526(54.6%) 318(1.7%) ival-mult ...rval-sampler/src/biginterval.rkt:75:0 bf* [44] 50.2% bfmax* [45] 23.4% foldl [54] 13.5% bfmin* [48] 6.9% ...higher-order.rkt:361:33 [56] 3.0% ------------------------------------------------------------------------ ??? [29] 100.0% [34] 1462(7.6%) 1462(7.6%) ->flonum ...interval-sampler/src/programs.rkt:36:0 ------------------------------------------------------------------------ ??? [28] 100.0% [35] 1288(6.7%) 350(1.8%) mk-ival ...terval-sampler/src/biginterval.rkt:33:0 bf [47] 72.8% ------------------------------------------------------------------------ atab-add-altn [30] 100.0% [36] 706(3.7%) 0(0.0%) override-at-pnts ...r/src/core/alt-table.rkt:145:0 errors [46] 100.0% ------------------------------------------------------------------------ ??? [29] 100.0% [37] 694(3.6%) 396(2.1%) sample-multi-bounded ...ampler/src/points.rkt:43:0 loop [52] 42.9% ------------------------------------------------------------------------ ??? [28] 100.0% [38] 690(3.6%) 0(0.0%) ival-sub ...erval-sampler/src/biginterval.rkt:63:0 ??? [63] 100.0% ------------------------------------------------------------------------ location-do [39] 50.0% ??? [29] 50.0% [39] 670(3.5%) 0(0.0%) location-do ...erval-sampler/src/programs.rkt:89:0 location-do [39] 50.0% ??? [29] 50.0% ------------------------------------------------------------------------ ??? [29] 100.0% [40] 670(3.5%) 0(0.0%) ??? ...interval-sampler/src/core/simplify.rkt:31:0 loop [49] 51.3% iterate-egraph!7 [50] 48.7% ------------------------------------------------------------------------ atab-add-altn [30] 100.0% [41] 638(3.3%) 0(0.0%) best-and-tied-at-points ...ore/alt-table.rkt:122:0 errors [46] 100.0% ------------------------------------------------------------------------ rewriter [32] 100.0% [42] 300(1.6%) 300(1.6%) for-loop ...al-sampler/src/core/matcher.rkt:134:10 matcher [51] 50.0% ------------------------------------------------------------------------ ??? [29] 100.0% [43] 284(1.5%) 0(0.0%) best-alt ...nterval-sampler/src/mainloop.rkt:109:0 argmins [53] 100.0% ------------------------------------------------------------------------ ival-mult [33] 100.0% [44] 5284(27.4%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [63] 100.0% ------------------------------------------------------------------------ ival-mult [33] 100.0% [45] 2460(12.8%) 376(2.0%) bfmax* ...nterval-sampler/src/biginterval.rkt:72:0 foldl [54] 84.7% ------------------------------------------------------------------------ ??? [62] 17.4% best-and-tied-at-points [41] 39.2% override-at-pnts [36] 43.4% [46] 1628(8.4%) 0(0.0%) errors ...biginterval-sampler/src/points.rkt:261:0 eval-prog [55] 78.0% for-loop [57] 22.0% ------------------------------------------------------------------------ mk-ival [35] 100.0% [47] 938(4.9%) 0(0.0%) bf ...ath-lib/math/private/bigfloat/mpfr.rkt:601:2 new-mpfr [73] 67.6% ??? [74] 32.4% ------------------------------------------------------------------------ ival-mult [33] 100.0% [48] 724(3.8%) 0(0.0%) bfmin* ...nterval-sampler/src/biginterval.rkt:69:0 foldl [54] 52.2% ...higher-order.rkt:361:33 [56] 47.8% ------------------------------------------------------------------------ ??? [40] 100.0% [49] 344(1.8%) 0(0.0%) loop ...terval-sampler/src/core/simplify.rkt:184:2 for-loop [58] 100.0% ------------------------------------------------------------------------ ??? [40] 100.0% [50] 326(1.7%) 0(0.0%) iterate-egraph!7 ...ler/src/core/simplify.rkt:55:0 one-iter [59] 100.0% ------------------------------------------------------------------------ for-loop [60] 50.0% for-loop [42] 50.0% [51] 300(1.6%) 0(0.0%) matcher ...rval-sampler/src/core/matcher.rkt:168:2 for-loop [60] 50.0% rewriter [32] 50.0% ------------------------------------------------------------------------ sample-multi-bounded [37] 100.0% [52] 298(1.5%) 0(0.0%) loop ...ie/biginterval-sampler/src/points.rkt:65:6 random-exp [61] 100.0% ------------------------------------------------------------------------ best-alt [43] 100.0% [53] 284(1.5%) 0(0.0%) argmins ...iginterval-sampler/src/common.rkt:142:0 ??? [62] 100.0% ------------------------------------------------------------------------ bfmin* [48] 9.7% ival-mult [33] 36.7% bfmax* [45] 53.6% [54] 3888(20.2%) 0(0.0%) foldl ...et/collects/racket/private/list.rkt:229:4 ??? [63] 100.0% ------------------------------------------------------------------------ errors [46] 100.0% [55] 1270(6.6%) 0(0.0%) eval-prog ...terval-sampler/src/programs.rkt:108:0 inductor [65] 48.0% temp68_2 [66] 27.4% loop [86] 24.6% ------------------------------------------------------------------------ ival-mult [33] 47.6% bfmin* [48] 52.4% [56] 660(3.4%) 0(0.0%) ...higher-order.rkt:361:33 (unknown source) ??? [64] 100.0% ------------------------------------------------------------------------ errors [46] 100.0% [57] 358(1.9%) 0(0.0%) for-loop ...ginterval-sampler/src/points.rkt:264:4 ??? [19] 100.0% ------------------------------------------------------------------------ loop [49] 100.0% [58] 344(1.8%) 0(0.0%) for-loop ...l-sampler/src/core/simplify.rkt:177:29 argmin [67] 100.0% ------------------------------------------------------------------------ iterate-egraph!7 [50] 100.0% [59] 326(1.7%) 0(0.0%) one-iter ...val-sampler/src/core/simplify.rkt:66:0 for-loop [68] 100.0% ------------------------------------------------------------------------ matcher [51] 100.0% [60] 300(1.6%) 0(0.0%) for-loop ...al-sampler/src/core/matcher.rkt:184:21 matcher [51] 100.0% ------------------------------------------------------------------------ loop [52] 100.0% [61] 298(1.5%) 298(1.5%) random-exp ...nterval-sampler/src/common.rkt:243:0 ------------------------------------------------------------------------ argmins [53] 100.0% [62] 284(1.5%) 0(0.0%) ??? ...biginterval-sampler/src/mainloop.rkt:111:19 errors [46] 100.0% ------------------------------------------------------------------------ ival-sub [38] 7.0% foldl [54] 39.4% bf* [44] 53.6% [63] 9862(51.2%) 2508(13.0%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [69] 74.6% ------------------------------------------------------------------------ ??? [80] 34.3% ...higher-order.rkt:361:33 [56] 65.7% [64] 1004(5.2%) 660(3.4%) ??? ...cts/racket/contract/private/list.rkt:209:10 ??? [80] 34.3% ------------------------------------------------------------------------ eval-prog [55] 36.6% inductor [65] 63.4% [65] 610(3.2%) 0(0.0%) inductor ...nterval-sampler/src/programs.rkt:113:4 inductor [65] 63.4% table-ref [70] 36.6% ------------------------------------------------------------------------ eval-prog [55] 100.0% [66] 348(1.8%) 0(0.0%) temp68_2 (unknown source) compile16 [71] 100.0% ------------------------------------------------------------------------ for-loop [58] 100.0% [67] 344(1.8%) 0(0.0%) argmin ...92/racket/collects/racket/list.rkt:765:0 for-loop [72] 100.0% ------------------------------------------------------------------------ for-loop [68] 50.0% one-iter [59] 50.0% [68] 326(1.7%) 326(1.7%) for-loop ...al-sampler/src/core/simplify.rkt:76:12 for-loop [68] 50.0% ------------------------------------------------------------------------ ??? [63] 100.0% [69] 7354(38.2%) 0(0.0%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:821:4 new-mpfr [73] 78.1% ??? [74] 21.9% ------------------------------------------------------------------------ inductor [65] 100.0% [70] 610(3.2%) 0(0.0%) table-ref ...ginterval-sampler/src/common.rkt:47:0 ??? [75] 100.0% ------------------------------------------------------------------------ temp68_2 [66] 100.0% [71] 348(1.8%) 0(0.0%) compile16 (unknown source) temp74_0 [76] 100.0% ------------------------------------------------------------------------ argmin [67] 100.0% [72] 344(1.8%) 0(0.0%) for-loop ...nterval-sampler/src/programs.rkt:183:2 ...mpler/src/common.rkt:48:2 [77]100.0% ------------------------------------------------------------------------ bf [47] 9.9% bfmul [69] 90.1% [73] 6374(33.1%) 6374(33.1%) new-mpfr ...b/math/private/bigfloat/mpfr.rkt:335:0 ------------------------------------------------------------------------ bf [47] 15.8% bfmul [69] 84.2% [74] 1918(10.0%) 1918(10.0%) ??? /home/p92/racket/collects/ffi/unsafe.rkt:898:4 ------------------------------------------------------------------------ table-ref [70] 100.0% [75] 610(3.2%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:798:4 for-loop [78] 100.0% ------------------------------------------------------------------------ compile16 [71] 100.0% [76] 348(1.8%) 0(0.0%) temp74_0 (unknown source) expand-single [79] 100.0% ------------------------------------------------------------------------ for-loop [72] 100.0% [77] 344(1.8%) 0(0.0%) ...mpler/src/common.rkt:48:2 ...e/arr-i.rkt:918:19 ??? [80] 100.0% ------------------------------------------------------------------------ ??? [75] 100.0% [78] 610(3.2%) 326(1.7%) for-loop ...acket/contract/private/list.rkt:804:12 ??? [83] 46.6% ------------------------------------------------------------------------ temp74_0 [76] 100.0% [79] 348(1.8%) 0(0.0%) expand-single (unknown source) expand-capturing-lifts [81] 100.0% ------------------------------------------------------------------------ ??? [64] 50.0% ...mpler/src/common.rkt:48:2 [77] 50.0% [80] 344(1.8%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:367:4 ??? [64] 50.0% ??? [82] 50.0% ------------------------------------------------------------------------ loop [86] 47.3% expand-single [79] 52.7% [81] 660(3.4%) 0(0.0%) expand-capturing-lifts (unknown source) lambda-clause-expander [84] 52.7% loop [86] 47.3% ------------------------------------------------------------------------ ??? [80] 100.0% [82] 344(1.8%) 344(1.8%) ??? ...ects/racket/contract/private/guts.rkt:746:8 ------------------------------------------------------------------------ for-loop [78] 100.0% [83] 284(1.5%) 0(0.0%) ??? ...private/unconstrained-domain-arrow.rkt:74:4 loop [85] 100.0% ------------------------------------------------------------------------ expand-capturing-lifts [81] 100.0% [84] 348(1.8%) 0(0.0%) lambda-clause-expander (unknown source) loop [86] 100.0% ------------------------------------------------------------------------ ??? [83] 25.0% loop [85] 75.0% [85] 284(1.5%) 284(1.5%) loop ...ts/racket/contract/private/blame.rkt:320:2 loop [85] 75.0% ------------------------------------------------------------------------ expand-capturing-lifts [81] 23.6% eval-prog [55] 23.6% apply-transformer52 [6] 26.4% lambda-clause-expander [84] 26.4% [86] 660(3.4%) 312(1.6%) loop (unknown source) dispatch-transformer41 [2] 26.4% core-form-sym [87] 26.4% expand-capturing-lifts [81] 23.6% ------------------------------------------------------------------------ loop [86] 100.0% [87] 348(1.8%) 0(0.0%) core-form-sym (unknown source) resolve+shift28 [88] 100.0% ------------------------------------------------------------------------ core-form-sym [87] 100.0% [88] 348(1.8%) 348(1.8%) resolve+shift28 (unknown source) ------------------------------------------------------------------------