Profiling results ----------------- Total cpu time observed: 28446ms (out of 28876ms) Number of samples taken: 82 (once every 347ms) ======================================================================== Caller Idx Total Self Name+src Local% ms(pct) ms(pct) Callee ======================================================================== ??? [31] 100.0% [1] 25742(90.5%) 0(0.0%) profile-thunk16 ...t/pkgs/profile-lib/main.rkt:9:0 run [7] 100.0% ------------------------------------------------------------------------ ??? [56] 100.0% [2] 21066(74.1%) 0(0.0%) iterate-egraph!13 ...s/src/core/simplify.rkt:102:0 one-iter [8] 98.4% map-enodes [13] 1.6% ------------------------------------------------------------------------ ??? [31] 100.0% [3] 20462(71.9%) 0(0.0%) run-improve50 ...-functions/src/mainloop.rkt:234:0 for-loop [9] 100.0% ------------------------------------------------------------------------ match-e [29] 100.0% [4] 1070(3.8%) 362(1.3%) for-loop ...mo-functions/src/core/ematch.rkt:59:11 for-loop [10] 66.4% list-cartesian-product [12] 16.7% ------------------------------------------------------------------------ ??? [71] 29.5% loop [58] 70.5% [5] 1018(3.6%) 0(0.0%) ??? ...tract/private/arrow-higher-order.rkt:342:33 ??? [56] 70.5% best-alt [14] 29.5% ------------------------------------------------------------------------ ??? [56] 100.0% [6] 360(1.3%) 0(0.0%) mk-egraph ...o-functions/src/core/egraph.rkt:123:0 expr->enode [11] 100.0% ------------------------------------------------------------------------ profile-thunk16 [1] 100.0% [7] 25742(90.5%) 0(0.0%) run ...share/racket/pkgs/profile-lib/main.rkt:39:2 ??? [31] 100.0% ------------------------------------------------------------------------ iterate-egraph!13 [2] 100.0% [8] 20734(72.9%) 0(0.0%) one-iter ...-functions/src/core/simplify.rkt:113:0 loop [16] 88.4% for-loop [17] 6.5% apply-match [22] 5.2% ------------------------------------------------------------------------ run-improve50 [3] 100.0% [9] 20462(71.9%) 0(0.0%) for-loop ...demo-functions/src/mainloop.rkt:249:10 run-iter! [15] 100.0% ------------------------------------------------------------------------ for-loop [4] 100.0% [10] 1070(3.8%) 0(0.0%) for-loop ...mo-functions/src/core/ematch.rkt:65:37 match-e [29] 100.0% ------------------------------------------------------------------------ mk-egraph [6] 100.0% [11] 360(1.3%) 0(0.0%) expr->enode ...functions/src/core/egraph.rkt:124:2 mk-enode! [37] 100.0% ------------------------------------------------------------------------ for-loop [4] 100.0% [12] 358(1.3%) 0(0.0%) list-cartesian-product ...src/core/ematch.rkt:20:0 for-loop [18] 100.0% ------------------------------------------------------------------------ iterate-egraph!13 [2] 100.0% [13] 332(1.2%) 0(0.0%) map-enodes ...-functions/src/core/egraph.rkt:139:0 for-loop [19] 100.0% ------------------------------------------------------------------------ ??? [5] 100.0% [14] 300(1.1%) 0(0.0%) best-alt ...e/web-demo-functions/src/glue.rkt:71:0 argmins [20] 100.0% ------------------------------------------------------------------------ for-loop [9] 100.0% [15] 20462(71.9%) 0(0.0%) run-iter! ...demo-functions/src/mainloop.rkt:215:0 simplify! [21] 96.7% finalize-iter! [23] 1.8% choose-best-alt! [26] 1.5% ------------------------------------------------------------------------ one-iter [8] 100.0% [16] 18320(64.4%) 0(0.0%) loop ...cket/collects/racket/private/map.rkt:52:19 apply-match [22] 100.0% ------------------------------------------------------------------------ for-loop [17] 37.6% one-iter [8] 62.4% [17] 1342(4.7%) 694(2.4%) for-loop ...functions/src/core/simplify.rkt:123:12 for-loop [17] 37.6% match-e [29] 24.1% ------------------------------------------------------------------------ list-cartesian-product [12] 100.0% [18] 358(1.3%) 358(1.3%) for-loop ...emo-functions/src/core/ematch.rkt:24:8 ------------------------------------------------------------------------ map-enodes [13] 100.0% [19] 332(1.2%) 0(0.0%) for-loop ...-functions/src/core/simplify.rkt:173:2 compose [24] 100.0% ------------------------------------------------------------------------ best-alt [14] 100.0% [20] 300(1.1%) 0(0.0%) argmins ...web-demo-functions/src/common.rkt:150:0 composed [25] 100.0% ------------------------------------------------------------------------ run-iter! [15] 100.0% [21] 19788(69.6%) 0(0.0%) simplify! ...demo-functions/src/mainloop.rkt:155:0 for-loop [27] 100.0% ------------------------------------------------------------------------ one-iter [8] 5.5% loop [16] 94.5% [22] 19392(68.2%) 310(1.1%) apply-match ...nctions/src/core/simplify.rkt:130:2 for-loop [28] 78.7% update-leader! [35] 12.5% match-e [29] 5.5% reduce-to-single! [30] 1.7% ------------------------------------------------------------------------ run-iter! [15] 100.0% [23] 374(1.3%) 0(0.0%) finalize-iter! ...functions/src/mainloop.rkt:169:0 ??? [59] 100.0% ------------------------------------------------------------------------ for-loop [19] 100.0% [24] 332(1.2%) 332(1.2%) compose ...collects/racket/private/list.rkt:385:12 ------------------------------------------------------------------------ argmins [20] 100.0% [25] 300(1.1%) 0(0.0%) composed ...ollects/racket/private/list.rkt:321:16 errors [85] 100.0% ------------------------------------------------------------------------ run-iter! [15] 100.0% [26] 300(1.1%) 0(0.0%) choose-best-alt! ...nctions/src/mainloop.rkt:115:0 ??? [59] 100.0% ------------------------------------------------------------------------ simplify! [21] 100.0% [27] 19788(69.6%) 0(0.0%) for-loop ...-demo-functions/src/mainloop.rkt:159:6 ??? [31] 100.0% ------------------------------------------------------------------------ apply-match [22] 100.0% [28] 15268(53.7%) 0(0.0%) for-loop ...functions/src/core/simplify.rkt:145:10 merge-egraph-nodes! [32] 59.5% mk-enode! [37] 20.2% loop! [42] 11.1% substitute-e [33] 9.2% ------------------------------------------------------------------------ apply-match [22] 27.7% for-loop [10] 34.5% for-loop [17] 37.7% [29] 1718(6.0%) 0(0.0%) match-e ...demo-functions/src/core/ematch.rkt:46:0 for-loop [4] 55.5% ??? [59] 44.5% ------------------------------------------------------------------------ apply-match [22] 100.0% [30] 328(1.2%) 0(0.0%) reduce-to-single! ...ons/src/core/egraph.rkt:324:0 for-loop [34] 100.0% ------------------------------------------------------------------------ for-loop [27] 23.7% run [7] 34.4% [31] 27798(97.7%) 0(0.0%) ??? ...lects/racket/private/more-scheme.rkt:261:28 profile-thunk16 [1] 34.4% ??? [56] 29.8% run-improve50 [3] 24.9% loop [36] 10.8% ------------------------------------------------------------------------ merge-egraph-nodes! [32] 2.0% for-loop [38] 5.6% for-loop [28] 92.4% [32] 9082(31.9%) 0(0.0%) merge-egraph-nodes! ...s/src/core/egraph.rkt:152:0 update-leader! [35] 69.1% loop! [42] 11.7% for-loop [38] 9.6% for-loop [39] 3.9% enode-merge! [40] 3.7% merge-egraph-nodes! [32] 2.0% ------------------------------------------------------------------------ substitute-e [33] 38.4% for-loop [28] 61.6% [33] 1400(4.9%) 718(2.5%) substitute-e ...functions/src/core/ematch.rkt:71:0 substitute-e [33] 38.4% mk-enode! [37] 23.1% ??? [59] 12.8% ------------------------------------------------------------------------ reduce-to-single! [30] 100.0% [34] 328(1.2%) 328(1.2%) for-loop ...mo-functions/src/core/egraph.rkt:325:8 ------------------------------------------------------------------------ apply-match [22] 26.7% merge-egraph-nodes! [32] 73.3% [35] 9038(31.8%) 368(1.3%) update-leader! ...ctions/src/core/egraph.rkt:217:0 for-loop [41] 95.9% ------------------------------------------------------------------------ ??? [31] 100.0% [36] 5642(19.8%) 0(0.0%) loop ...ie/web-demo-functions/src/points.rkt:193:2 make-exacts* [47] 56.6% loop [44] 43.4% ------------------------------------------------------------------------ substitute-e [33] 8.6% expr->enode [11] 9.6% for-loop [28] 81.8% [37] 3768(13.2%) 360(1.3%) mk-enode! ...o-functions/src/core/egraph.rkt:101:0 new-enode [43] 90.4% ------------------------------------------------------------------------ merge-egraph-nodes! [32] 100.0% [38] 1384(4.9%) 0(0.0%) for-loop ...o-functions/src/core/egraph.rkt:200:10 merge-egraph-nodes! [32] 74.0% loop! [42] 26.0% ------------------------------------------------------------------------ merge-egraph-nodes! [32] 100.0% [39] 358(1.3%) 0(0.0%) for-loop ...mo-functions/src/core/egraph.rkt:232:6 update-en-expr [72] 100.0% ------------------------------------------------------------------------ merge-egraph-nodes! [32] 100.0% [40] 334(1.2%) 0(0.0%) enode-merge! ...functions/src/core/enode.rkt:129:0 custom-set-intersect! [45] 100.0% ------------------------------------------------------------------------ update-leader! [35] 100.0% [41] 8670(30.5%) 334(1.2%) for-loop ...mo-functions/src/core/egraph.rkt:222:6 for-loop [46] 96.1% ------------------------------------------------------------------------ for-loop [38] 5.2% loop [58] 25.3% for-loop [28] 34.1% merge-egraph-nodes! [32] 35.4% [42] 3470(12.2%) 0(0.0%) loop! ...b-demo-functions/src/core/enode.rkt:192:2 custom-set-union [51] 39.1% loop [58] 35.6% set [49] 25.3% ------------------------------------------------------------------------ mk-enode! [37] 100.0% [43] 3408(12.0%) 0(0.0%) new-enode ...emo-functions/src/core/enode.rkt:97:0 type-of-enode-expr [48] 100.0% ------------------------------------------------------------------------ loop [36] 100.0% [44] 2450(8.6%) 0(0.0%) loop ...ie/web-demo-functions/src/points.rkt:151:2 loop [50] 70.2% make-exacts* [47] 29.8% ------------------------------------------------------------------------ enode-merge! [40] 100.0% [45] 334(1.2%) 334(1.2%) custom-set-intersect! ...ivate/set-types.rkt:269:0 ------------------------------------------------------------------------ for-loop [41] 100.0% [46] 8336(29.3%) 0(0.0%) for-loop ...mo-functions/src/core/egraph.rkt:223:8 hash-update! [52] 100.0% ------------------------------------------------------------------------ loop [44] 18.6% loop [36] 81.4% [47] 3922(13.8%) 0(0.0%) make-exacts* ...emo-functions/src/points.rkt:138:0 map [53] 54.4% loop [58] 45.6% ------------------------------------------------------------------------ new-enode [43] 100.0% [48] 3408(12.0%) 0(0.0%) type-of-enode-expr ...ions/src/core/enode.rkt:68:0 get-sigs [54] 100.0% ------------------------------------------------------------------------ loop! [42] 100.0% [49] 1754(6.2%) 1754(6.2%) set ...collects/racket/private/set-types.rkt:981:0 ------------------------------------------------------------------------ loop [44] 100.0% [50] 1720(6.0%) 0(0.0%) loop ...ie/web-demo-functions/src/points.rkt:141:4 map [53] 100.0% ------------------------------------------------------------------------ loop! [42] 100.0% [51] 1358(4.8%) 334(1.2%) custom-set-union ...et/private/set-types.rkt:168:0 for-loop [55] 75.4% ------------------------------------------------------------------------ for-loop [46] 100.0% [52] 8336(29.3%) 0(0.0%) hash-update! ...ket/private/more-scheme.rkt:362:13 ??? [57] 100.0% ------------------------------------------------------------------------ loop [50] 44.6% make-exacts* [47] 55.4% [53] 3854(13.5%) 0(0.0%) map ...acket/collects/racket/private/map.rkt:20:13 loop [58] 100.0% ------------------------------------------------------------------------ type-of-enode-expr [48] 100.0% [54] 3408(12.0%) 0(0.0%) get-sigs ...-demo-functions/src/type-check.rkt:5:0 ??? [60] 58.4% ??? [56] 20.4% ??? [59] 10.6% ...tions/src/common.rkt:47:2 [62] 10.6% ------------------------------------------------------------------------ custom-set-union [51] 100.0% [55] 1024(3.6%) 332(1.2%) for-loop ...cts/racket/private/set-types.rkt:174:3 for-loop [61] 67.6% ------------------------------------------------------------------------ get-sigs [54] 1.0% ??? [5] 3.1% ??? [31] 46.5% for-loop [63] 47.9% [56] 22848(80.3%) 1078(3.8%) ??? ...llects/racket/private/norm-define.rkt:53:83 for-loop [63] 47.9% iterate-egraph!13 [2] 45.9% loop [68] 1.5% mk-egraph [6] 0.5% ??? [73] 0.5% ------------------------------------------------------------------------ hash-update! [52] 100.0% [57] 8336(29.3%) 0(0.0%) ??? ...b-demo-functions/src/core/egraph.rkt:225:24 for-loop [64] 100.0% ------------------------------------------------------------------------ make-exacts* [47] 0.0% map [53] 0.3% loop! [42] 1.5% loop [58] 98.2% [58] 7754(27.3%) 358(1.3%) loop ...cket/collects/racket/private/map.rkt:25:19 loop [58] 98.2% loop! [42] 1.4% ??? [92] 0.3% ??? [5] 0.0% ------------------------------------------------------------------------ choose-best-alt! [26] 12.5% substitute-e [33] 15.0% get-sigs [54] 15.1% finalize-iter! [23] 15.6% match-e [29] 41.7% [59] 2392(8.4%) 350(1.2%) ??? ...contract/private/arrow-val-first.rkt:357:18 hash-has-key? [65] 57.2% for-loop [69] 15.6% ??? [71] 12.5% ------------------------------------------------------------------------ get-sigs [54] 100.0% [60] 1990(7.0%) 0(0.0%) ??? ...ts/racket/contract/private/arr-i.rkt:804:10 for-loop [66] 51.3% ??? [67] 48.7% ------------------------------------------------------------------------ for-loop [55] 100.0% [61] 692(2.4%) 692(2.4%) for-loop ...cts/racket/private/set-types.rkt:178:5 ------------------------------------------------------------------------ get-sigs [54] 100.0% [62] 362(1.3%) 0(0.0%) ...tions/src/common.rkt:47:2 ...e/arr-i.rkt:906:19 ??? [70] 100.0% ------------------------------------------------------------------------ ??? [56] 100.0% [63] 22130(77.8%) 0(0.0%) for-loop ...-functions/src/core/simplify.rkt:63:12 ??? [56] 100.0% ------------------------------------------------------------------------ ??? [57] 100.0% [64] 8336(29.3%) 6916(24.3%) for-loop ...o-functions/src/core/egraph.rkt:226:26 update-en-expr [72] 17.0% ------------------------------------------------------------------------ ??? [59] 100.0% [65] 1368(4.8%) 358(1.3%) hash-has-key? ...et/private/more-scheme.rkt:368:13 ??? [73] 73.8% ------------------------------------------------------------------------ ??? [60] 100.0% [66] 1020(3.6%) 0(0.0%) for-loop ...racket/contract/private/hash.rkt:239:6 ??? [74] 100.0% ------------------------------------------------------------------------ ??? [60] 100.0% [67] 970(3.4%) 0(0.0%) ??? ...ects/racket/contract/private/hash.rkt:217:4 ??? [75] 100.0% ------------------------------------------------------------------------ ??? [56] 100.0% [68] 704(2.5%) 0(0.0%) loop ...demo-functions/src/core/simplify.rkt:215:2 pass [76] 100.0% ------------------------------------------------------------------------ ??? [59] 100.0% [69] 374(1.3%) 0(0.0%) for-loop ...-functions/src/core/alt-table.rkt:51:2 atab-add-altn [77] 100.0% ------------------------------------------------------------------------ ...tions/src/common.rkt:47:2 [62]100.0% [70] 362(1.3%) 362(1.3%) ??? ...ects/racket/contract/private/list.rkt:342:4 ------------------------------------------------------------------------ ??? [59] 100.0% [71] 300(1.1%) 0(0.0%) ??? ...-demo-functions/src/core/alt-table.rkt:54:0 ??? [5] 100.0% ------------------------------------------------------------------------ for-loop [39] 20.1% for-loop [64] 79.9% [72] 1778(6.3%) 1778(6.3%) update-en-expr ...ctions/src/core/egraph.rkt:211:0 ------------------------------------------------------------------------ ??? [56] 24.9% hash-has-key? [65] 75.1% [73] 1344(4.7%) 300(1.1%) ??? ...ects/racket/contract/private/list.rkt:737:4 for-loop [78] 77.7% ------------------------------------------------------------------------ for-loop [66] 100.0% [74] 1020(3.6%) 0(0.0%) ??? ...cts/racket/contract/private/list.rkt:187:10 ??? [79] 100.0% ------------------------------------------------------------------------ ??? [67] 100.0% [75] 970(3.4%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:169:2 ??? [80] 100.0% ------------------------------------------------------------------------ loop [68] 100.0% [76] 704(2.5%) 0(0.0%) pass ...demo-functions/src/core/simplify.rkt:205:2 for-loop [82] 51.1% hash-set*+ [83] 48.9% ------------------------------------------------------------------------ for-loop [69] 100.0% [77] 374(1.3%) 0(0.0%) atab-add-altn ...ions/src/core/alt-table.rkt:208:0 best-and-tied-at-points [81] 100.0% ------------------------------------------------------------------------ ??? [73] 100.0% [78] 1044(3.7%) 696(2.4%) for-loop ...acket/contract/private/list.rkt:743:12 arrow-higher-order:lnp [88] 33.3% ------------------------------------------------------------------------ ??? [74] 100.0% [79] 1020(3.6%) 0(0.0%) ??? ...ects/racket/contract/private/list.rkt:686:7 ??? [84] 100.0% ------------------------------------------------------------------------ ??? [75] 100.0% [80] 970(3.4%) 322(1.1%) ??? ...ects/racket/contract/private/list.rkt:678:5 for-loop [86] 66.8% ------------------------------------------------------------------------ atab-add-altn [77] 100.0% [81] 374(1.3%) 0(0.0%) best-and-tied-at-points ...ore/alt-table.rkt:122:0 errors [85] 100.0% ------------------------------------------------------------------------ pass [76] 100.0% [82] 360(1.3%) 0(0.0%) for-loop ...functions/src/core/simplify.rkt:208:29 argmin [87] 100.0% ------------------------------------------------------------------------ pass [76] 100.0% [83] 344(1.2%) 344(1.2%) hash-set*+ ...unctions/src/core/simplify.rkt:187:0 ------------------------------------------------------------------------ ??? [79] 100.0% [84] 1020(3.6%) 1020(3.6%) ??? ...ects/racket/contract/private/guts.rkt:644:8 ------------------------------------------------------------------------ composed [25] 44.5% best-and-tied-at-points [81] 55.5% [85] 674(2.4%) 0(0.0%) errors .../web-demo-functions/src/points.rkt:218:0 for-loop [89] 100.0% ------------------------------------------------------------------------ ??? [80] 100.0% [86] 648(2.3%) 0(0.0%) for-loop ...racket/contract/private/list.rkt:680:9 add-list-context [90] 100.0% ------------------------------------------------------------------------ for-loop [82] 100.0% [87] 360(1.3%) 0(0.0%) argmin ...re/racket/collects/racket/list.rkt:743:0 for-loop [91] 100.0% ------------------------------------------------------------------------ for-loop [78] 100.0% [88] 348(1.2%) 348(1.2%) arrow-higher-order:lnp ...w-higher-order.rkt:597:7 ------------------------------------------------------------------------ errors [85] 100.0% [89] 674(2.4%) 0(0.0%) for-loop ...eb-demo-functions/src/points.rkt:221:4 ??? [92] 100.0% ------------------------------------------------------------------------ for-loop [86] 100.0% [90] 648(2.3%) 648(2.3%) add-list-context ...ontract/private/list.rkt:752:0 ------------------------------------------------------------------------ argmin [87] 100.0% [91] 360(1.3%) 360(1.3%) for-loop ...-demo-functions/src/programs.rkt:199:2 ------------------------------------------------------------------------ for-loop [89] 12.0% loop [58] 88.0% [92] 5598(19.7%) 358(1.3%) ??? ...e/web-demo-functions/src/programs.rkt:154:4 ??? [93] 61.9% ??? [94] 31.7% ------------------------------------------------------------------------ ??? [92] 100.0% [93] 3466(12.2%) 1722(6.1%) ??? ...acket/collects/racket/private/kw.rkt:444:14 ??? [98] 20.4% ??? [96] 19.6% bf* [97] 10.3% ------------------------------------------------------------------------ ??? [92] 100.0% [94] 1774(6.2%) 362(1.3%) ??? ...contract/private/../../private/kw.rkt:761:9 real->bigfloat [95] 79.6% ------------------------------------------------------------------------ ??? [94] 100.0% [95] 1412(5.0%) 1070(3.8%) real->bigfloat .../private/bigfloat/mpfr.rkt:451:0 ??? [100] 24.2% ------------------------------------------------------------------------ ??? [93] 100.0% [96] 678(2.4%) 678(2.4%) ??? ...rivate/unconstrained-domain-arrow.rkt:40:13 ------------------------------------------------------------------------ ??? [93] 100.0% [97] 358(1.3%) 0(0.0%) bf* ...ath/private/bigfloat/bigfloat-mpfr.rkt:95:0 ??? [98] 100.0% ------------------------------------------------------------------------ bf* [97] 33.6% ??? [93] 66.4% [98] 1066(3.7%) 0(0.0%) ??? ...-racket/utils/simple-result-arrow.rkt:39:12 bfadd [99] 100.0% ------------------------------------------------------------------------ ??? [98] 100.0% [99] 1066(3.7%) 716(2.5%) bfadd ...-lib/math/private/bigfloat/mpfr.rkt:819:4 ??? [100] 32.8% ------------------------------------------------------------------------ real->bigfloat [95] 49.4% bfadd [99] 50.6% [100] 692(2.4%) 692(2.4%) ??? .../share/racket/collects/ffi/unsafe.rkt:861:4 ------------------------------------------------------------------------