Profiling results ----------------- Total cpu time observed: 25646ms (out of 26232ms) Number of samples taken: 71 (once every 361ms) (Hiding functions with self<1.0% and local<2.0%: 1 of 91 hidden) ======================================================================= Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ======================================================================= ??? [25] 100.0% [1] 23384(91.2%) 0(0.0%) run-improve48 ...-operators/src/mainloop.rkt:256:0 for-loop [7] 61.7% setup-prog!41 [8] 21.8% for-loop [9] 16.5% ----------------------------------------------------------------------- ??? [25] 100.0% [2] 22770(88.8%) 0(0.0%) profile-thunk16 ...t/pkgs/profile-lib/main.rkt:9:0 run [6] 100.0% ----------------------------------------------------------------------- hash-ref! [65] 100.0% [3] 3736(14.6%) 0(0.0%) ??? ...parametric-operators/src/programs.rkt:194:5 loop [57] 82.1% hash-ref! [65] 17.9% ----------------------------------------------------------------------- ??? [29] 100.0% [4] 3244(12.6%) 0(0.0%) loop ...tric-operators/src/core/simplify.rkt:228:2 pass [10] 100.0% ----------------------------------------------------------------------- for-loop [89] 16.6% loop [57] 83.4% [5] 2026(7.9%) 0(0.0%) ??? ...parametric-operators/src/programs.rkt:156:4 ??? [11] 100.0% ----------------------------------------------------------------------- profile-thunk16 [2] 100.0% [6] 22770(88.8%) 0(0.0%) run ...share/racket/pkgs/profile-lib/main.rkt:39:2 ??? [25] 100.0% ----------------------------------------------------------------------- run-improve48 [1] 100.0% [7] 14420(56.2%) 0(0.0%) for-loop ...etric-operators/src/mainloop.rkt:271:8 run-iter! [12] 100.0% ----------------------------------------------------------------------- run-improve48 [1] 100.0% [8] 5102(19.9%) 0(0.0%) setup-prog!41 ...c-operators/src/mainloop.rkt:63:0 prepare-points [13] 100.0% ----------------------------------------------------------------------- run-improve48 [1] 100.0% [9] 3862(15.1%) 0(0.0%) for-loop ...tric-operators/src/mainloop.rkt:268:29 ??? [29] 100.0% ----------------------------------------------------------------------- loop [4] 100.0% [10] 3244(12.6%) 0(0.0%) pass ...tric-operators/src/core/simplify.rkt:218:2 for-loop [14] 100.0% ----------------------------------------------------------------------- ??? [5] 100.0% [11] 2026(7.9%) 336(1.3%) ??? ...acket/collects/racket/private/kw.rkt:444:14 ??? [66] 83.4% ----------------------------------------------------------------------- for-loop [7] 100.0% [12] 14420(56.2%) 0(0.0%) run-iter! ...tric-operators/src/mainloop.rkt:237:0 simplify! [15] 97.7% choose-best-alt! [19] 2.3% ----------------------------------------------------------------------- setup-prog!41 [8] 100.0% [13] 5102(19.9%) 0(0.0%) prepare-points ...c-operators/src/points.rkt:214:0 loop [16] 59.4% make-exacts* [21] 27.7% loop [23] 12.9% ----------------------------------------------------------------------- pass [10] 100.0% [14] 3244(12.6%) 0(0.0%) for-loop ...operators/src/core/simplify.rkt:221:29 argmin [17] 59.3% loop [18] 40.7% ----------------------------------------------------------------------- run-iter! [12] 100.0% [15] 14084(54.9%) 0(0.0%) simplify! ...tric-operators/src/mainloop.rkt:177:0 for-loop [20] 100.0% ----------------------------------------------------------------------- prepare-points [13] 100.0% [16] 3030(11.8%) 0(0.0%) loop ...-parametric-operators/src/points.rkt:158:2 make-exacts* [21] 88.8% loop [23] 11.2% ----------------------------------------------------------------------- for-loop [14] 100.0% [17] 1924(7.5%) 0(0.0%) argmin ...re/racket/collects/racket/list.rkt:743:0 for-loop [22] 82.7% expression-cost [24] 17.3% ----------------------------------------------------------------------- for-loop [14] 100.0% [18] 1320(5.1%) 0(0.0%) loop ...hare/racket/collects/racket/list.rkt:729:4 for-loop [22] 74.5% expression-cost [24] 25.5% ----------------------------------------------------------------------- run-iter! [12] 100.0% [19] 336(1.3%) 0(0.0%) choose-best-alt! ...erators/src/mainloop.rkt:113:0 ??? [53] 100.0% ----------------------------------------------------------------------- simplify! [15] 100.0% [20] 14084(54.9%) 0(0.0%) for-loop ...etric-operators/src/mainloop.rkt:181:6 ??? [25] 100.0% ----------------------------------------------------------------------- prepare-points [13] 34.4% loop [16] 65.6% [21] 4104(16.0%) 0(0.0%) make-exacts* ...ric-operators/src/points.rkt:138:0 eval-prog [26] 83.1% loop [57] 16.9% ----------------------------------------------------------------------- loop [18] 38.2% argmin [17] 61.8% [22] 2576(10.0%) 0(0.0%) for-loop ...etric-operators/src/programs.rkt:210:2 ??? [29] 74.8% ...ators/src/common.rkt:48:2 [28] 25.2% ----------------------------------------------------------------------- loop [16] 33.9% prepare-points [13] 66.1% [23] 998(3.9%) 0(0.0%) loop ...-parametric-operators/src/points.rkt:141:4 loop [57] 100.0% ----------------------------------------------------------------------- argmin [17] 49.7% loop [18] 50.3% [24] 668(2.6%) 0(0.0%) expression-cost ...perators/src/programs.rkt:209:0 compile [27] 100.0% ----------------------------------------------------------------------- for-loop [20] 18.5% run [6] 35.7% [25] 25312(98.7%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 run-improve48 [1] 38.1% profile-thunk16 [2] 35.7% ??? [29] 26.2% ----------------------------------------------------------------------- make-exacts* [21] 100.0% [26] 3412(13.3%) 0(0.0%) eval-prog ...tric-operators/src/programs.rkt:150:0 hash-ref! [65] 100.0% ----------------------------------------------------------------------- expression-cost [24] 100.0% [27] 668(2.6%) 0(0.0%) compile ...metric-operators/src/programs.rkt:186:0 hash-ref! [65] 100.0% ----------------------------------------------------------------------- for-loop [22] 100.0% [28] 648(2.5%) 332(1.3%) ...ators/src/common.rkt:48:2 ...e/arr-i.rkt:906:19 ??? [30] 48.8% ----------------------------------------------------------------------- for-loop [22] 3.2% for-loop [9] 9.6% for-loop [32] 22.7% ??? [29] 25.7% ??? [25] 38.0% [29] 20208(78.8%) 336(1.3%) ??? ...llects/racket/private/norm-define.rkt:53:83 iterate-egraph!13 [31] 42.0% ??? [29] 25.7% for-loop [32] 22.7% loop [4] 6.4% ??? [33] 2.1% return/no-unsupplied [35] 0.5% ----------------------------------------------------------------------- ...ators/src/common.rkt:48:2 [28]100.0% [30] 316(1.2%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:342:4 ??? [34] 100.0% ----------------------------------------------------------------------- ??? [29] 100.0% [31] 16964(66.1%) 0(0.0%) iterate-egraph!13 ...s/src/core/simplify.rkt:105:0 one-iter [36] 98.0% ----------------------------------------------------------------------- ??? [29] 100.0% [32] 9488(37.0%) 0(0.0%) for-loop ...-operators/src/core/simplify.rkt:66:12 ??? [29] 100.0% ----------------------------------------------------------------------- ??? [29] 100.0% [33] 1282(5.0%) 986(3.8%) ??? ...ects/racket/contract/private/list.rkt:737:4 for-loop [38] 23.1% ----------------------------------------------------------------------- ??? [30] 100.0% [34] 316(1.2%) 316(1.2%) ??? ...ects/racket/contract/private/hash.rkt:222:6 ----------------------------------------------------------------------- ??? [29] 100.0% [35] 310(1.2%) 0(0.0%) return/no-unsupplied ...ct/private/arr-i.rkt:533:0 filter [37] 100.0% ----------------------------------------------------------------------- iterate-egraph!13 [31] 100.0% [36] 16630(64.8%) 0(0.0%) one-iter ...-operators/src/core/simplify.rkt:116:0 for-loop [39] 58.1% for-loop [40] 41.9% ----------------------------------------------------------------------- return/no-unsupplied [35] 100.0% [37] 310(1.2%) 310(1.2%) filter ...t/collects/racket/private/list.rkt:256:2 ----------------------------------------------------------------------- ??? [33] 100.0% [38] 296(1.2%) 0(0.0%) for-loop ...acket/contract/private/list.rkt:743:12 do-chaperone-procedure [41] 100.0% ----------------------------------------------------------------------- for-loop [39] 43.4% one-iter [36] 56.6% [39] 9662(37.7%) 3718(14.5%) for-loop ...operators/src/core/simplify.rkt:126:12 for-loop [39] 43.4% match-e [42] 29.0% ??? [44] 1.7% ----------------------------------------------------------------------- one-iter [36] 100.0% [40] 6968(27.2%) 0(0.0%) for-loop ...-operators/src/core/simplify.rkt:164:2 for-loop [43] 77.0% match-e [42] 23.0% ----------------------------------------------------------------------- for-loop [38] 100.0% [41] 296(1.2%) 296(1.2%) do-chaperone-procedure ...et/private/kw.rkt:1616:2 ----------------------------------------------------------------------- for-loop [52] 11.7% for-loop [40] 15.6% for-loop [39] 72.7% [42] 7216(28.1%) 3986(15.5%) match-e ...tric-operators/src/core/ematch.rkt:46:0 for-loop [45] 37.3% custom-in-set [47] 4.6% loop [57] 2.2% for-loop [52] 1.5% variable? [50] 1.5% ----------------------------------------------------------------------- for-loop [40] 100.0% [43] 5362(20.9%) 0(0.0%) for-loop ...operators/src/core/simplify.rkt:146:10 loop! [51] 56.1% merge-egraph-nodes! [46] 37.9% substitute-e [49] 6.0% ----------------------------------------------------------------------- for-loop [39] 100.0% [44] 334(1.3%) 0(0.0%) ??? ...rametric-operators/src/core/ematch.rkt:50:5 for-loop [48] 100.0% ----------------------------------------------------------------------- match-e [42] 100.0% [45] 3230(12.6%) 1290(5.0%) for-loop ...ic-operators/src/core/ematch.rkt:59:11 for-loop [52] 39.5% list-cartesian-product [54] 25.2% ----------------------------------------------------------------------- for-loop [56] 8.0% merge-egraph-nodes! [46] 8.8% for-loop [43] 83.2% [46] 2032(7.9%) 0(0.0%) merge-egraph-nodes! ...s/src/core/egraph.rkt:153:0 loop! [51] 49.8% update-leader! [55] 33.4% merge-egraph-nodes! [46] 8.8% for-loop [56] 8.0% ----------------------------------------------------------------------- match-e [42] 100.0% [47] 334(1.3%) 334(1.3%) custom-in-set ...acket/private/set-types.rkt:577:0 ----------------------------------------------------------------------- ??? [44] 100.0% [48] 334(1.3%) 334(1.3%) for-loop ...ric-operators/src/core/ematch.rkt:51:7 ----------------------------------------------------------------------- substitute-e [49] 50.0% for-loop [43] 50.0% [49] 324(1.3%) 0(0.0%) substitute-e ...operators/src/core/ematch.rkt:71:0 substitute-e [49] 50.0% ??? [53] 50.0% ----------------------------------------------------------------------- match-e [42] 100.0% [50] 322(1.3%) 0(0.0%) variable? ...operators/src/syntax/syntax.rkt:698:0 ??? [53] 100.0% ----------------------------------------------------------------------- merge-egraph-nodes! [46] 23.5% loop [57] 27.2% for-loop [43] 49.4% [51] 4360(17.0%) 358(1.4%) loop! ...metric-operators/src/core/enode.rkt:175:2 custom-set-union [58] 45.7% loop [57] 42.3% update-en-expr [59] 4.2% set [63] 3.7% ----------------------------------------------------------------------- match-e [42] 10.2% for-loop [45] 89.8% [52] 1580(6.2%) 0(0.0%) for-loop ...ic-operators/src/core/ematch.rkt:65:37 match-e [42] 100.0% ----------------------------------------------------------------------- variable? [50] 32.8% substitute-e [49] 33.0% choose-best-alt! [19] 34.2% [53] 982(3.8%) 646(2.5%) ??? ...contract/private/arrow-val-first.rkt:357:18 ??? [61] 34.2% ----------------------------------------------------------------------- for-loop [45] 100.0% [54] 970(3.8%) 636(2.5%) list-cartesian-product ...src/core/ematch.rkt:20:0 for-loop [62] 34.4% ----------------------------------------------------------------------- merge-egraph-nodes! [46] 100.0% [55] 678(2.6%) 0(0.0%) update-leader! ...rators/src/core/egraph.rkt:218:0 custom-set-union! [60] 53.1% for-loop [64] 46.9% ----------------------------------------------------------------------- merge-egraph-nodes! [46] 100.0% [56] 326(1.3%) 0(0.0%) for-loop ...c-operators/src/core/egraph.rkt:201:10 merge-egraph-nodes! [46] 100.0% ----------------------------------------------------------------------- make-exacts* [21] 0.3% loop [23] 0.6% match-e [42] 3.7% loop! [51] 9.8% ??? [3] 36.4% loop [57] 47.3% [57] 8436(32.9%) 976(3.8%) loop ...cket/collects/racket/private/map.rkt:25:19 loop [57] 47.3% hash-ref! [65] 36.4% loop! [51] 9.3% for-loop [68] 2.0% ??? [5] 0.9% ----------------------------------------------------------------------- loop! [51] 100.0% [58] 2652(10.3%) 1322(5.2%) custom-set-union ...et/private/set-types.rkt:168:0 for-loop [67] 50.2% ----------------------------------------------------------------------- loop! [51] 100.0% [59] 362(1.4%) 362(1.4%) update-en-expr ...rators/src/core/egraph.rkt:212:0 ----------------------------------------------------------------------- update-leader! [55] 100.0% [60] 360(1.4%) 360(1.4%) custom-set-union! ...t/private/set-types.rkt:245:0 ----------------------------------------------------------------------- ??? [53] 100.0% [61] 336(1.3%) 0(0.0%) ??? ...etric-operators/src/core/alt-table.rkt:54:0 ??? [66] 100.0% ----------------------------------------------------------------------- list-cartesian-product [54] 100.0% [62] 334(1.3%) 334(1.3%) for-loop ...ric-operators/src/core/ematch.rkt:24:8 ----------------------------------------------------------------------- loop! [51] 100.0% [63] 326(1.3%) 326(1.3%) set ...collects/racket/private/set-types.rkt:981:0 ----------------------------------------------------------------------- update-leader! [55] 100.0% [64] 318(1.2%) 0(0.0%) for-loop ...ic-operators/src/core/egraph.rkt:223:6 for-loop [69] 100.0% ----------------------------------------------------------------------- compile [27] 5.5% ??? [3] 10.9% eval-prog [26] 19.4% loop [57] 64.2% [65] 4080(15.9%) 668(2.6%) hash-ref! ...racket/private/more-scheme.rkt:373:13 ??? [3] 75.1% bigfloat-hash [70] 19.4% ----------------------------------------------------------------------- ??? [61] 16.6% ??? [11] 83.4% [66] 2026(7.9%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:342:33 bf-complex-mult [71] 66.6% bf-complex-add [73] 16.8% best-alt [74] 16.6% ----------------------------------------------------------------------- custom-set-union [58] 100.0% [67] 1330(5.2%) 336(1.3%) for-loop ...cts/racket/private/set-types.rkt:174:3 for-loop [72] 74.7% ----------------------------------------------------------------------- loop [57] 100.0% [68] 334(1.3%) 334(1.3%) for-loop ...-operators/src/core/simplify.rkt:186:2 ----------------------------------------------------------------------- for-loop [64] 100.0% [69] 318(1.2%) 0(0.0%) for-loop ...ic-operators/src/core/egraph.rkt:224:8 hash-update! [75] 100.0% ----------------------------------------------------------------------- hash-ref! [65] 100.0% [70] 3412(13.3%) 0(0.0%) bigfloat-hash ...h/private/bigfloat/mpfr.rkt:165:0 bfcanonicalize [76] 79.7% bigfloat->sig+exp [78] 10.3% sig+exp->bigfloat [79] 10.1% ----------------------------------------------------------------------- ??? [66] 100.0% [71] 1350(5.3%) 0(0.0%) bf-complex-mult ...erators/src/bigcomplex.rkt:33:0 bf* [77] 50.8% ??? [82] 49.2% ----------------------------------------------------------------------- for-loop [67] 100.0% [72] 994(3.9%) 994(3.9%) for-loop ...cts/racket/private/set-types.rkt:178:5 ----------------------------------------------------------------------- ??? [66] 100.0% [73] 340(1.3%) 0(0.0%) bf-complex-add ...perators/src/bigcomplex.rkt:24:0 ??? [82] 100.0% ----------------------------------------------------------------------- ??? [66] 100.0% [74] 336(1.3%) 0(0.0%) best-alt ...etric-operators/src/mainloop.rkt:108:0 argmins [80] 100.0% ----------------------------------------------------------------------- for-loop [69] 100.0% [75] 318(1.2%) 0(0.0%) hash-update! ...ket/private/more-scheme.rkt:362:13 ??? [81] 100.0% ----------------------------------------------------------------------- bigfloat-hash [70] 100.0% [76] 2718(10.6%) 2718(10.6%) bfcanonicalize .../private/bigfloat/mpfr.rkt:155:0 ----------------------------------------------------------------------- bf-complex-mult [71] 100.0% [77] 686(2.7%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [82] 100.0% ----------------------------------------------------------------------- bigfloat-hash [70] 100.0% [78] 350(1.4%) 0(0.0%) bigfloat->sig+exp ...ivate/bigfloat/mpfr.rkt:370:0 new-mpz [83] 100.0% ----------------------------------------------------------------------- bigfloat-hash [70] 100.0% [79] 344(1.3%) 344(1.3%) sig+exp->bigfloat ...ivate/bigfloat/mpfr.rkt:385:0 ----------------------------------------------------------------------- best-alt [74] 100.0% [80] 336(1.3%) 0(0.0%) argmins ...rametric-operators/src/common.rkt:153:0 composed [84] 100.0% ----------------------------------------------------------------------- hash-update! [75] 100.0% [81] 318(1.2%) 0(0.0%) ??? ...metric-operators/src/core/egraph.rkt:226:24 for-loop [85] 100.0% ----------------------------------------------------------------------- bf-complex-add [73] 20.1% bf-complex-mult [71] 39.3% bf* [77] 40.6% [82] 1690(6.6%) 0(0.0%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfadd [86] 80.0% bfneg [87] 20.0% ----------------------------------------------------------------------- bigfloat->sig+exp [78] 100.0% [83] 350(1.4%) 350(1.4%) new-mpz ...-lib/math/private/bigfloat/gmp.rkt:73:0 ----------------------------------------------------------------------- argmins [80] 100.0% [84] 336(1.3%) 0(0.0%) composed ...ollects/racket/private/list.rkt:321:16 errors [88] 100.0% ----------------------------------------------------------------------- ??? [81] 100.0% [85] 318(1.2%) 318(1.2%) for-loop ...c-operators/src/core/egraph.rkt:227:26 ----------------------------------------------------------------------- ??? [82] 100.0% [86] 1352(5.3%) 1026(4.0%) bfadd ...-lib/math/private/bigfloat/mpfr.rkt:819:4 ??? [90] 24.1% ----------------------------------------------------------------------- ??? [82] 100.0% [87] 338(1.3%) 338(1.3%) bfneg ...-lib/math/private/bigfloat/mpfr.rkt:616:4 ----------------------------------------------------------------------- composed [84] 100.0% [88] 336(1.3%) 0(0.0%) errors ...arametric-operators/src/points.rkt:271:0 for-loop [89] 100.0% ----------------------------------------------------------------------- errors [88] 100.0% [89] 336(1.3%) 0(0.0%) for-loop ...ametric-operators/src/points.rkt:274:4 ??? [5] 100.0% ----------------------------------------------------------------------- bfadd [86] 100.0% [90] 326(1.3%) 326(1.3%) ??? .../share/racket/collects/ffi/unsafe.rkt:861:4 -----------------------------------------------------------------------