Profiling results ----------------- Total cpu time observed: 117120ms (out of 121428ms) Number of samples taken: 273 (once every 429ms) (Hiding functions with self<1.0% and local<2.0%: 1 of 67 hidden) ========================================================================= Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ========================================================================= run [10] 49.4% [1] 115474(98.6%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [5] 49.4% prepare-points [18] 47.4% run-improve48 [7] 3.2% ------------------------------------------------------------------------- loop [63] 100.0% [2] 106082(90.6%) 0(0.0%) ??? ...parametric-operators/src/programs.rkt:156:4 ??? [6] 100.0% ------------------------------------------------------------------------- ??? [40] 100.0% [3] 966(0.8%) 0(0.0%) loop ...tric-operators/src/core/simplify.rkt:228:2 pass [8] 100.0% ------------------------------------------------------------------------- loop [63] 100.0% [4] 348(0.3%) 0(0.0%) for-loop ...-operators/src/core/simplify.rkt:186:2 eval-const-expr [9] 100.0% ------------------------------------------------------------------------- ??? [1] 100.0% [5] 114012(97.3%) 0(0.0%) profile-thunk16 ...t/pkgs/profile-lib/main.rkt:9:0 run [10] 100.0% ------------------------------------------------------------------------- ??? [2] 100.0% [6] 106082(90.6%) 7898(6.7%) ??? ...acket/collects/racket/private/kw.rkt:444:14 ??? [11] 89.2% ??? [13] 2.3% ------------------------------------------------------------------------- ??? [1] 100.0% [7] 7382(6.3%) 0(0.0%) run-improve48 ...-operators/src/mainloop.rkt:256:0 setup-prog!41 [12] 62.4% for-loop [15] 28.4% for-loop [17] 9.2% ------------------------------------------------------------------------- loop [3] 100.0% [8] 966(0.8%) 0(0.0%) pass ...tric-operators/src/core/simplify.rkt:218:2 for-loop [16] 100.0% ------------------------------------------------------------------------- for-loop [4] 100.0% [9] 348(0.3%) 0(0.0%) eval-const-expr ...perators/src/programs.rkt:168:0 f137 [14] 100.0% ------------------------------------------------------------------------- profile-thunk16 [5] 100.0% [10] 114012(97.3%) 0(0.0%) run ...share/racket/pkgs/profile-lib/main.rkt:39:2 ??? [1] 100.0% ------------------------------------------------------------------------- f137 [14] 0.2% ??? [49] 0.2% map [22] 0.7% loop [63] 3.2% ??? [6] 95.4% [11] 99200(84.7%) 2104(1.8%) ??? ...tract/private/arrow-higher-order.rkt:342:33 bf-complex-mult [19] 88.0% bf-complex-add [20] 7.8% ??? [40] 1.7% best-alt [25] 0.2% ...ators/src/common.rkt:48:2 [23] 0.2% ------------------------------------------------------------------------- run-improve48 [7] 100.0% [12] 4604(3.9%) 0(0.0%) setup-prog!41 ...c-operators/src/mainloop.rkt:63:0 prepare-points [18] 100.0% ------------------------------------------------------------------------- ??? [6] 100.0% [13] 2492(2.1%) 2492(2.1%) ??? ...ects/racket/contract/private/prop.rkt:493:4 ------------------------------------------------------------------------- eval-prog [53] 1.4% f137 [14] 12.3% eval-const-expr [9] 14.2% hash-ref! [57] 72.1% [14] 2444(2.1%) 0(0.0%) f137 .../collects/racket/match/compiler.rkt:507:40 map [22] 72.1% ...ators/src/common.rkt:48:2 [23] 14.2% f137 [14] 12.3% ??? [11] 1.4% ------------------------------------------------------------------------- run-improve48 [7] 100.0% [15] 2096(1.8%) 0(0.0%) for-loop ...etric-operators/src/mainloop.rkt:271:8 run-iter! [21] 100.0% ------------------------------------------------------------------------- pass [8] 100.0% [16] 966(0.8%) 0(0.0%) for-loop ...operators/src/core/simplify.rkt:221:29 argmin [24] 68.9% loop [26] 31.1% ------------------------------------------------------------------------- run-improve48 [7] 100.0% [17] 682(0.6%) 0(0.0%) for-loop ...tric-operators/src/mainloop.rkt:268:29 ??? [40] 100.0% ------------------------------------------------------------------------- setup-prog!41 [12] 4.1% ??? [1] 95.9% [18] 112696(96.2%) 0(0.0%) prepare-points ...c-operators/src/points.rkt:214:0 loop [28] 35.9% make-exacts* [38] 32.1% loop [37] 31.9% ------------------------------------------------------------------------- ??? [11] 100.0% [19] 87276(74.5%) 5450(4.7%) bf-complex-mult ...erators/src/bigcomplex.rkt:33:0 bf* [27] 54.7% ??? [36] 34.8% ...higher-order.rkt:346:33 [29] 4.2% ------------------------------------------------------------------------- ??? [11] 100.0% [20] 7764(6.6%) 0(0.0%) bf-complex-add ...perators/src/bigcomplex.rkt:24:0 ??? [36] 94.8% ...higher-order.rkt:346:33 [29] 5.2% ------------------------------------------------------------------------- for-loop [15] 100.0% [21] 2096(1.8%) 0(0.0%) run-iter! ...tric-operators/src/mainloop.rkt:237:0 localize-error [30] 84.1% choose-best-alt! [34] 15.9% ------------------------------------------------------------------------- ??? [31] 20.8% f137 [14] 79.2% [22] 1762(1.5%) 0(0.0%) map ...acket/collects/racket/private/map.rkt:20:13 ??? [31] 79.2% ??? [11] 20.8% ------------------------------------------------------------------------- ??? [11] 49.0% f137 [14] 51.0% [23] 682(0.6%) 0(0.0%) ...ators/src/common.rkt:48:2 ...e/arr-i.rkt:906:19 ??? [33] 100.0% ------------------------------------------------------------------------- for-loop [16] 100.0% [24] 666(0.6%) 0(0.0%) argmin ...re/racket/collects/racket/list.rkt:743:0 for-loop [32] 100.0% ------------------------------------------------------------------------- ??? [11] 100.0% [25] 334(0.3%) 0(0.0%) best-alt ...etric-operators/src/mainloop.rkt:108:0 argmins [35] 100.0% ------------------------------------------------------------------------- for-loop [16] 100.0% [26] 300(0.3%) 0(0.0%) loop ...hare/racket/collects/racket/list.rkt:729:4 for-loop [32] 100.0% ------------------------------------------------------------------------- bf-complex-mult [19] 100.0% [27] 47758(40.8%) 746(0.6%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [36] 98.4% ------------------------------------------------------------------------- prepare-points [18] 100.0% [28] 40470(34.6%) 0(0.0%) loop ...-parametric-operators/src/points.rkt:158:2 loop [37] 51.3% make-exacts* [38] 48.7% ------------------------------------------------------------------------- bf-complex-add [20] 9.9% bf-complex-mult [19] 90.1% [29] 4082(3.5%) 778(0.7%) ...higher-order.rkt:346:33 (unknown source) ??? [39] 80.9% ------------------------------------------------------------------------- run-iter! [21] 100.0% [30] 1762(1.5%) 0(0.0%) localize-error ...ators/src/core/localize.rkt:47:0 hash-ref! [57] 100.0% ------------------------------------------------------------------------- map [22] 11.4% loop [63] 88.6% [31] 1762(1.5%) 0(0.0%) ??? ...ket/collects/racket/private/list.rkt:323:18 hash-ref! [57] 95.1% map [22] 4.9% ------------------------------------------------------------------------- loop [26] 31.1% argmin [24] 68.9% [32] 966(0.8%) 344(0.3%) for-loop ...etric-operators/src/programs.rkt:210:2 ??? [40] 64.4% ------------------------------------------------------------------------- ??? [39] 25.5% ...ators/src/common.rkt:48:2 [23] 74.5% [33] 682(0.6%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:342:4 ??? [39] 74.5% ??? [45] 25.5% ------------------------------------------------------------------------- run-iter! [21] 100.0% [34] 334(0.3%) 0(0.0%) choose-best-alt! ...erators/src/mainloop.rkt:113:0 ??? [41] 100.0% ------------------------------------------------------------------------- best-alt [25] 100.0% [35] 334(0.3%) 0(0.0%) argmins ...rametric-operators/src/common.rkt:153:0 composed [42] 100.0% ------------------------------------------------------------------------- bf-complex-add [20] 8.7% bf-complex-mult [19] 35.9% bf* [27] 55.5% [36] 84762(72.4%) 4682(4.0%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfmul [43] 91.0% bfneg [44] 3.5% ------------------------------------------------------------------------- loop [28] 36.6% prepare-points [18] 63.4% [37] 56778(48.5%) 0(0.0%) loop ...-parametric-operators/src/points.rkt:141:4 loop [63] 100.0% ------------------------------------------------------------------------- loop [28] 35.2% prepare-points [18] 64.8% [38] 55918(47.7%) 0(0.0%) make-exacts* ...ric-operators/src/points.rkt:138:0 loop [63] 92.5% eval-prog [53] 7.5% ------------------------------------------------------------------------- ??? [33] 17.1% ...higher-order.rkt:346:33 [29] 82.9% [39] 3986(3.4%) 2592(2.2%) ??? ...cts/racket/contract/private/list.rkt:187:10 ??? [45] 26.2% ??? [33] 8.7% ------------------------------------------------------------------------- for-loop [32] 6.1% for-loop [17] 10.1% ??? [40] 26.5% ??? [11] 45.9% [40] 3374(2.9%) 634(0.5%) ??? ...llects/racket/private/norm-define.rkt:53:83 ??? [40] 26.5% iterate-egraph!13 [46] 15.3% loop [3] 11.2% ??? [47] 11.0% ??? [48] 10.7% return/no-unsupplied [52] 9.2% ??? [51] 3.2% ------------------------------------------------------------------------- choose-best-alt! [34] 100.0% [41] 334(0.3%) 0(0.0%) ??? ...contract/private/arrow-val-first.rkt:357:18 ??? [49] 100.0% ------------------------------------------------------------------------- argmins [35] 100.0% [42] 334(0.3%) 0(0.0%) composed ...ollects/racket/private/list.rkt:321:16 errors [50] 100.0% ------------------------------------------------------------------------- ??? [36] 100.0% [43] 77112(65.8%) 53142(45.4%) bfmul ...-lib/math/private/bigfloat/mpfr.rkt:819:4 ??? [66] 31.1% ------------------------------------------------------------------------- ??? [36] 100.0% [44] 2968(2.5%) 1930(1.6%) bfneg ...-lib/math/private/bigfloat/mpfr.rkt:616:4 ??? [66] 35.0% ------------------------------------------------------------------------- ??? [33] 25.0% ??? [39] 75.0% [45] 1394(1.2%) 1394(1.2%) ??? ...ects/racket/contract/private/guts.rkt:644:8 ------------------------------------------------------------------------- ??? [40] 100.0% [46] 1030(0.9%) 0(0.0%) iterate-egraph!13 ...s/src/core/simplify.rkt:105:0 one-iter [54] 66.2% map-enodes [56] 33.8% ------------------------------------------------------------------------- ??? [40] 100.0% [47] 372(0.3%) 0(0.0%) ??? ...racket/collects/racket/private/kw.rkt:761:9 ??? [55] 100.0% ------------------------------------------------------------------------- ??? [40] 100.0% [48] 362(0.3%) 0(0.0%) ??? ...-racket/utils/simple-result-arrow.rkt:60:15 ??? [66] 100.0% ------------------------------------------------------------------------- ??? [41] 100.0% [49] 334(0.3%) 0(0.0%) ??? ...etric-operators/src/core/alt-table.rkt:54:0 ??? [11] 100.0% ------------------------------------------------------------------------- composed [42] 100.0% [50] 334(0.3%) 0(0.0%) errors ...arametric-operators/src/points.rkt:271:0 eval-prog [53] 100.0% ------------------------------------------------------------------------- ??? [40] 100.0% [51] 322(0.3%) 322(0.3%) ??? ...ects/racket/contract/private/list.rkt:737:4 ------------------------------------------------------------------------- ??? [40] 100.0% [52] 310(0.3%) 310(0.3%) return/no-unsupplied ...ct/private/arr-i.rkt:533:0 ------------------------------------------------------------------------- errors [50] 7.4% make-exacts* [38] 92.6% [53] 4508(3.8%) 0(0.0%) eval-prog ...tric-operators/src/programs.rkt:150:0 hash-ref! [57] 92.6% f137 [14] 7.4% ------------------------------------------------------------------------- iterate-egraph!13 [46] 100.0% [54] 682(0.6%) 0(0.0%) one-iter ...-operators/src/core/simplify.rkt:116:0 for-loop [58] 100.0% ------------------------------------------------------------------------- ??? [47] 100.0% [55] 372(0.3%) 372(0.3%) ??? ...s/racket/contract/private/arr-i.rkt:1233:27 ------------------------------------------------------------------------- iterate-egraph!13 [46] 100.0% [56] 348(0.3%) 0(0.0%) map-enodes ...-operators/src/core/egraph.rkt:140:0 loop [63] 100.0% ------------------------------------------------------------------------- localize-error [30] 3.2% eval-prog [53] 13.9% ??? [31] 26.5% loop [63] 56.4% [57] 5936(5.1%) 0(0.0%) hash-ref! ...racket/private/more-scheme.rkt:373:13 ??? [60] 56.4% parse-loop12 [61] 26.5% bigfloat-hash [59] 13.9% f137 [14] 3.2% ------------------------------------------------------------------------- for-loop [58] 50.0% one-iter [54] 50.0% [58] 682(0.6%) 0(0.0%) for-loop ...operators/src/core/simplify.rkt:126:12 for-loop [58] 50.0% match-e [62] 50.0% ------------------------------------------------------------------------- hash-ref! [57] 100.0% [59] 4174(3.6%) 0(0.0%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:165:0 bfcanonicalize [64] 73.5% sig+exp->bigfloat [65] 26.5% ------------------------------------------------------------------------- hash-ref! [57] 100.0% [60] 3874(3.3%) 0(0.0%) ??? ...parametric-operators/src/programs.rkt:194:5 loop [63] 100.0% ------------------------------------------------------------------------- hash-ref! [57] 100.0% [61] 1762(1.5%) 0(0.0%) parse-loop12 ...s/racket/match/compiler.rkt:418:15 loop [63] 100.0% ------------------------------------------------------------------------- for-loop [58] 100.0% [62] 682(0.6%) 682(0.6%) match-e ...tric-operators/src/core/ematch.rkt:46:0 ------------------------------------------------------------------------- map-enodes [56] 0.0% parse-loop12 [61] 0.4% loop [37] 0.5% make-exacts* [38] 0.7% ??? [60] 3.4% loop [63] 94.9% [63] 114506(97.8%) 334(0.3%) loop ...cket/collects/racket/private/map.rkt:25:19 loop [63] 94.9% hash-ref! [57] 3.4% ??? [2] 1.1% ??? [31] 0.4% ??? [11] 0.1% for-loop [4] 0.0% ------------------------------------------------------------------------- bigfloat-hash [59] 100.0% [64] 3068(2.6%) 3068(2.6%) bfcanonicalize .../private/bigfloat/mpfr.rkt:155:0 ------------------------------------------------------------------------- bigfloat-hash [59] 100.0% [65] 1106(0.9%) 756(0.6%) sig+exp->bigfloat ...ivate/bigfloat/mpfr.rkt:385:0 ??? [66] 31.6% ------------------------------------------------------------------------- sig+exp->bigfloat [65] 1.4% ??? [48] 1.4% bfneg [44] 4.0% bfmul [43] 93.2% [66] 25720(22.0%) 25720(22.0%) ??? .../share/racket/collects/ffi/unsafe.rkt:861:4 -------------------------------------------------------------------------